Sep 01 00:13:00 stefans-speaker go-librespot[4076]: time="2026-09-01T00:13:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 00:13:00 stefans-speaker go-librespot[4076]: time="2026-09-01T00:13:00+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:00 stefans-speaker go-librespot[4076]: time="2026-09-01T00:13:00+02:00" level=debug msg="completed challenge" Sep 01 00:13:00 stefans-speaker go-librespot[4076]: time="2026-09-01T00:13:00+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:00 stefans-speaker go-librespot[4076]: time="2026-09-01T00:13:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "outputs"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "albumart"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Plugin example_plugin is not enabled Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "inputs"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "updater_comm"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Plugin mpdemulation is not enabled Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "rest_api"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Loading plugin "websocket"... Sep 01 00:13:00 stefans-speaker volumio[4001]: info: Starting Socket.io Server version 1.7.4 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Loading i18n strings for locale nl Sep 01 00:13:01 stefans-speaker volumio[4001]: Updating browse sources language Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::initPlayerControls Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: Express server listening on port 3000 Sep 01 00:13:01 stefans-speaker volumio[4001]: [Metrics] WebUI: 8s 150.74ms Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::resetVolumioState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::getcurrentVolume Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Cannot read play queue from file Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Volumio Network Manager: Network status updated: 2 Sep 01 00:13:01 stefans-speaker volumio[4084]: Forking 3 albumart workers Sep 01 00:13:01 stefans-speaker volumio[4001]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::pushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::updateTrackBlock Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrackBlock Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::setRepeat null single undefined Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::pushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::setRandom null Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::pushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Setting Device type: Raspberry PI Sep 01 00:13:01 stefans-speaker volumio[4001]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreStateMachine::pushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Completed loading Core Plugins Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Preparing to generate the ALSA configuration file Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Asound.conf file unchanged, so no further update is needed Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Output device has changed, restarting MPD Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Output device has changed, restarting Shairport Sync Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:01 stefans-speaker volumio[4001]: info: ___________ START PLUGINS ___________ Sep 01 00:13:01 stefans-speaker sudo[4138]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:01 stefans-speaker volumio[4001]: info: ControllerMpd::onStart: Initializing MPD Sep 01 00:13:01 stefans-speaker volumio[4001]: info: Creating MPD Configuration file Sep 01 00:13:01 stefans-speaker sudo[4140]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:01 stefans-speaker sudo[4138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:13:01 stefans-speaker sudo[4138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:01 stefans-speaker sudo[4140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:13:01 stefans-speaker sudo[4140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:02 stefans-speaker sudo[4138]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:02 stefans-speaker volumio[4001]: info: [1788214382012] CoreMusicLibrary::Adding element Media Servers Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:02 stefans-speaker volumio[4001]: info: UPNP Browser: Client initialized successfully Sep 01 00:13:02 stefans-speaker sudo[4147]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:02 stefans-speaker sudo[4150]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:02 stefans-speaker sudo[4150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:13:02 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 00:13:02 stefans-speaker sudo[4150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:02 stefans-speaker sudo[4147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:02 stefans-speaker sudo[4147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:02 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:13:02 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:13:02 stefans-speaker systemd[1]: mpd.service: Consumed 4.457s CPU time. Sep 01 00:13:02 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:13:02 stefans-speaker sudo[4147]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:02 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:13:02 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:13:02 stefans-speaker volumio[4001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:02 stefans-speaker volumio[4001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:02 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:02 stefans-speaker volumio[4001]: info: [1788214382235] CoreMusicLibrary::Adding element Last_100 Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:02 stefans-speaker volumio[4001]: info: [1788214382250] CoreMusicLibrary::Adding element Webradio Sep 01 00:13:02 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:13:02 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:13:02 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:13:02 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:13:02 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:13:02 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:13:02 stefans-speaker volumio[4001]: info: Initializing BBC Radios Sep 01 00:13:02 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:13:02 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:02 stefans-speaker volumio[4001]: info: Creating Spotify config file Sep 01 00:13:02 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:02 stefans-speaker sudo[4167]: root : unable to resolve host stefans-speaker: System error Sep 01 00:13:02 stefans-speaker sudo[4167]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:02 stefans-speaker sudo[4167]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 00:13:02 stefans-speaker sudo[4167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 00:13:02 stefans-speaker sudo[4167]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Volumio Calling Home Sep 01 00:13:03 stefans-speaker volumio[4101]: Starting albumart workers Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:03 stefans-speaker volumio[4100]: Starting albumart workers Sep 01 00:13:03 stefans-speaker volumio[4001]: info: MPD Permissions set Sep 01 00:13:03 stefans-speaker volumio[4001]: info: MPD Permissions set Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Volumio called home Sep 01 00:13:03 stefans-speaker volumio[4001]: info: Spotify config file written Sep 01 00:13:03 stefans-speaker volumio[4001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4099]: Starting albumart workers Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker sudo[4184]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker sudo[4184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 00:13:03 stefans-speaker sudo[4184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:03 stefans-speaker volumio[4001]: info: No need to fix Spotify hosts Sep 01 00:13:03 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:03 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:03 stefans-speaker go-librespot[4196]: go-librespot daemon starting... Sep 01 00:13:03 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 00:13:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 00:13:03 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:03 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:03 stefans-speaker go-librespot[4202]: go-librespot daemon starting... Sep 01 00:13:03 stefans-speaker sudo[4184]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:03 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:03+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:03 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:03+02:00" level=debug msg="app state loaded" Sep 01 00:13:03 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=info msg="zeroconf server listening on port 45385" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="obtained new client token: AAEmbvVIJue2mmSpFwNniz8JsnxIoM9P574LPPf7SpxaQAnDTMLqg7LXj+xMMLKxa2uo6OFTqCfKFMIh/hBMbCv1XAnjR36TC0szqMVp0m/+/6kNZHx4Z0SbCgf/GXaSjNEYUWT2Lzg0IEBSSthSNRFvOGP887s38TnOhhHfaYNsF//UmxOABxVK9hehbwBu7VRCCHn8gnkPOHxcxYPlR5pp5zkk6QQXkdG8TP1Y5zzJmBHyD51Ceuqh89o=" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=debug msg="completed challenge" Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:04 stefans-speaker volumio[4001]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:13:04 stefans-speaker volumio[4001]: SPOTIFY: BQAybwz_7FYuka7wsiJbCLKnvb1O-2WlViDXqG276JfYvfLYwMIR1pkKFimedBHNOY9klT6rLZYe626sW2ibmevJxga9rOFnEM1MDjFTiE6AD3jYjyUseKOkUxHYtX-bxuhrUJCwv7JKIO-Ci060UjVb6MXPL3ukVwV52NczjPFakOs9bWu82Z2v2A6YMFI6y4CwpU68VBueENMnFlg0WyqHhTwrbGwFBM9YcqtcbMUVH8ACcyRjm9GHm2XmhIyz2hrBEH7uTPKj-EWOdzsciEZhv3eUzAaKW3Y75ZMpWhir7vZdLGnPsghn3-34RdU Sep 01 00:13:04 stefans-speaker volumio[4001]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:13:04 stefans-speaker volumio[4001]: info: New Spotify access token = BQAybwz_7FYuka7wsiJbCLKnvb1O-2WlViDXqG276JfYvfLYwMIR1pkKFimedBHNOY9klT6rLZYe626sW2ibmevJxga9rOFnEM1MDjFTiE6AD3jYjyUseKOkUxHYtX-bxuhrUJCwv7JKIO-Ci060UjVb6MXPL3ukVwV52NczjPFakOs9bWu82Z2v2A6YMFI6y4CwpU68VBueENMnFlg0WyqHhTwrbGwFBM9YcqtcbMUVH8ACcyRjm9GHm2XmhIyz2hrBEH7uTPKj-EWOdzsciEZhv3eUzAaKW3Y75ZMpWhir7vZdLGnPsghn3-34RdU Sep 01 00:13:04 stefans-speaker volumio[4001]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 00:13:04 stefans-speaker go-librespot[4203]: time="2026-09-01T00:13:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:04 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:04 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Starting Shairport Sync Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Starting Shairport Sync Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Starting Shairport Sync Sep 01 00:13:05 stefans-speaker sudo[4223]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:05 stefans-speaker sudo[4223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:05 stefans-speaker sudo[4223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:05 stefans-speaker sudo[4225]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:05 stefans-speaker sudo[4225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:05 stefans-speaker sudo[4225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:05 stefans-speaker sudo[4228]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:05 stefans-speaker sudo[4228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:05 stefans-speaker sudo[4228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:05 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:13:05 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:13:05 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:05 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.891s CPU time. Sep 01 00:13:05 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:05 stefans-speaker sudo[4223]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:05 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:13:05 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:13:05 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:05 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:05 stefans-speaker sudo[4225]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:05 stefans-speaker sudo[4228]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Shairport-Sync Started Sep 01 00:13:05 stefans-speaker volumio[4001]: Error adding Membership: Error: addMembership EINVAL Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Shairport-Sync Started Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Shairport-Sync Started Sep 01 00:13:05 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:05 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:05 stefans-speaker volumio[4001]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 00:13:05 stefans-speaker volumio[4001]: info: Spotify Successfully logged in Sep 01 00:13:05 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:05 stefans-speaker volumio[4001]: info: [1788214385628] CoreMusicLibrary::Adding element Spotify Sep 01 00:13:05 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:05 stefans-speaker volumio[4001]: Cannot find translation for source Spotify Sep 01 00:13:05 stefans-speaker sudo[4270]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:05 stefans-speaker sudo[4270]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:13:05 stefans-speaker sudo[4270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:05 stefans-speaker sudo[4270]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:06 stefans-speaker volumio[4001]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 00:13:06 stefans-speaker sudo[4272]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:06 stefans-speaker sudo[4272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:13:06 stefans-speaker sudo[4272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:06 stefans-speaker sudo[4272]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:06 stefans-speaker sudo[4275]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:06 stefans-speaker sudo[4275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 00:13:06 stefans-speaker sudo[4275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:06 stefans-speaker sudo[4275]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:06 stefans-speaker volumio[4001]: info: Upmpdcli Daemon Started Sep 01 00:13:06 stefans-speaker volumio[4001]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CoreStateMachine::pushState Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:06 stefans-speaker volumio[4001]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:07 stefans-speaker mpd[4179]: 2026-09-01T00:13:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 00:13:07 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 00:13:07 stefans-speaker sudo[4150]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:07 stefans-speaker sudo[4140]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:07 stefans-speaker volumio[4001]: error: MPD error: The expression evaluated to a falsy value: Sep 01 00:13:07 stefans-speaker volumio[4001]: assert.ok(self.idling) Sep 01 00:13:07 stefans-speaker volumio[4001]: error: The expression evaluated to a falsy value: Sep 01 00:13:07 stefans-speaker volumio[4001]: assert.ok(self.idling) Sep 01 00:13:07 stefans-speaker volumio[4001]: info: MPD running with PID4179 Sep 01 00:13:07 stefans-speaker volumio[4001]: ,establishing connection Sep 01 00:13:07 stefans-speaker volumio[4001]: error: updateQueue error: null Sep 01 00:13:07 stefans-speaker volumio[4001]: info: Completed starting Core Plugins Sep 01 00:13:07 stefans-speaker volumio[4001]: info: ------------------------------------------- Sep 01 00:13:07 stefans-speaker volumio[4001]: info: ----- MyVolumio plugins startup ---- Sep 01 00:13:07 stefans-speaker volumio[4001]: info: ------------------------------------------- Sep 01 00:13:07 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 00:13:07 stefans-speaker volumio[4001]: error: updateQueue error: null Sep 01 00:13:07 stefans-speaker volumio[4001]: info: go-librespot daemon successfully initialized Sep 01 00:13:08 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 00:13:08 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:08 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:08 stefans-speaker go-librespot[4286]: go-librespot daemon starting... Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="app state loaded" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=info msg="zeroconf server listening on port 34067" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="obtained new client token: AAFBoitftypqMsdHPBCB/M/N/1LM2qwqM1I04bb/FlcFAGSBmDNuNVlxvfYKigxZg6RteLsA1C1vN9dBXKwQlmoXoZDOQybxMT8Mo2ewinvMuNJJdf1cAeYUnEd7xs8C1bFjpvwU0wZRo/ewDM+F0ML1AQ6wBpFsa4XZjT+tkn/kEMMM3kTBmJleOQgoPH9dT6oceg4aMV1ZFC+7iFQgdn0srl/HhZdHDq5Gmi2nActYtqipMM3Yh5duHtk=" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=debug msg="completed challenge" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:08 stefans-speaker go-librespot[4287]: time="2026-09-01T00:13:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:08 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:08 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:08 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:08] [connect] Successful connection Sep 01 00:13:08 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788214388 101 Sep 01 00:13:08 stefans-speaker volumio[4001]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Sep 01 00:13:10 stefans-speaker sudo[4297]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:10 stefans-speaker sudo[4297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:13:10 stefans-speaker sudo[4297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:10 stefans-speaker sudo[4299]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:10 stefans-speaker sudo[4299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:13:10 stefans-speaker sudo[4299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:10 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 00:13:10 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 00:13:10 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 00:13:10 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 00:13:10 stefans-speaker sudo[4299]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:10 stefans-speaker sudo[4297]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:10 stefans-speaker mpd_monitor.sh[4302]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 00:13:10 stefans-speaker volumio[4001]: info: Successfully started MPD Monitor Sep 01 00:13:10 stefans-speaker volumio[4001]: info: Successfully started MPD Monitor Sep 01 00:13:10 stefans-speaker volumio[4001]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:10 stefans-speaker volumio[4001]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 00:13:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:11 stefans-speaker go-librespot[4306]: go-librespot daemon starting... Sep 01 00:13:11 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:11+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:11 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:11+02:00" level=debug msg="app state loaded" Sep 01 00:13:11 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=info msg="zeroconf server listening on port 46129" Sep 01 00:13:12 stefans-speaker volumio[4001]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="obtained new client token: AAFcf8xxI2AmKI1wCUtioJCXGNG6FGmMcZqZ3cFbtN8wMk4/WMePlgsQNV/6+j2bflghAX12AdSYCvkruNINYunp+kM/wqUqf4UQGBL3LlAlEIUAOXRwzewky6vOsx/O3uujwjcSUV8EMLSCB9BOFoOXihovRXKWbhcmLcW1h91cfbcjQUUhy1VesiONT3/rTtYRW+PO2cPwHSXKxhmpKa7xCRz/TXJUd2ULT0J9VnnMCC/RaUm2DLCL" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=debug msg="completed challenge" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:12 stefans-speaker go-librespot[4307]: time="2026-09-01T00:13:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:13 stefans-speaker volumio[4001]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:13 stefans-speaker volumio[4001]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 00:13:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 00:13:15 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 00:13:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:15 stefans-speaker go-librespot[4315]: go-librespot daemon starting... Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=debug msg="app state loaded" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 01 00:13:15 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:15+02:00" level=info msg="zeroconf server listening on port 40673" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=debug msg="obtained new client token: AAFf0BF547pOLs6yxbeNf/KT07Z0vJMY0fMkaKhoS8x8ZVY/dlby1ySMWhFyym4y0+0KtM+6Huij+jfAsSjdgBehR0dwMLIzucQRWjuWmCp8ypdtZiwKfhKnqt8NUZ50kloSfdom2fu0Mqd3M9IfH5z8gxZqMaRid1BbeglfoQxC0h3f1Fq5Ht4i2JzA3qqfxi18sacyAuRUkgXfG2zhCNEqCZWBkm52dLQ9LXDANYuB1m80n8zkT5xBnMI=" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=debug msg="completed challenge" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:16 stefans-speaker go-librespot[4316]: time="2026-09-01T00:13:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:16 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 00:13:16 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 00:13:16 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:16 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:16 stefans-speaker volumio[4001]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 00:13:16 stefans-speaker volumio[4001]: info: MyVolumio login type: Token Sep 01 00:13:16 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 00:13:16 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 00:13:17 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 00:13:17 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 00:13:17 stefans-speaker volumio[4001]: info: Streaming services startup Sep 01 00:13:17 stefans-speaker volumio[4001]: info: Starting Streaming Daemon Sep 01 00:13:17 stefans-speaker sudo[4338]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:17 stefans-speaker volumio[4001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 00:13:17 stefans-speaker sudo[4338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:13:17 stefans-speaker sudo[4338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:17 stefans-speaker sudo[4338]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:17 stefans-speaker volumio[4001]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:17 stefans-speaker volumio[4001]: error: Cannot start Volumio Streaming Daemon Sep 01 00:13:17 stefans-speaker volumio[4001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:13:17 stefans-speaker volumio[4001]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:17 stefans-speaker volumio[4001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:13:17 stefans-speaker volumio[4001]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:18 stefans-speaker volumio[4001]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 00:13:18 stefans-speaker volumio[4001]: info: MyVolumio token set successfully Sep 01 00:13:18 stefans-speaker volumio[4001]: info: MYVOLUMIO: Adding device Sep 01 00:13:18 stefans-speaker volumio[4001]: info: MYVOLUMIO: Evaluating Server Sep 01 00:13:19 stefans-speaker volumio[4001]: info: MyVolumio status changed Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Streaming services startup Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Starting Streaming Daemon Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Removing audio output: Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Stoppping Tunnel 1 Sep 01 00:13:19 stefans-speaker sudo[4365]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:19 stefans-speaker sudo[4365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:13:19 stefans-speaker sudo[4365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:19 stefans-speaker sudo[4367]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:19 stefans-speaker sudo[4367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 00:13:19 stefans-speaker sudo[4367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 00:13:19 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Setting Geolocation for MyVolumio to eu8 Sep 01 00:13:19 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:19 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:19 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:19 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:19 stefans-speaker go-librespot[4370]: go-librespot daemon starting... Sep 01 00:13:19 stefans-speaker sudo[4365]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker volumio[4001]: error: Cannot start Volumio Streaming Daemon Sep 01 00:13:19 stefans-speaker volumio[4001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:13:19 stefans-speaker volumio[4001]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:19 stefans-speaker volumio[4001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="app state loaded" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:19 stefans-speaker sudo[4367]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:19 stefans-speaker volumio[4001]: info: Remote SSH Stopped Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=info msg="zeroconf server listening on port 44607" Sep 01 00:13:19 stefans-speaker volumio[4001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="obtained new client token: AAEve61US+48HJ4A0GaL7x+APuL6+9K7fsY7IhyCFg1ABSUDSPtEtn1DZe67HO0pHAARcW5X7z5E679UaztJe/DSiS/URWEGDtIy6zHHUY8K+FIVbAFwSePboJD68Pml6UHICi/de1ZBZ4qd8G8haIpTAEeAFjnb7Y71qVv8MZpX5GHlfY4zw8O9wJQPy2/w1X1PCo1WivDAS4hApU1WgIyNXoD+AOuHSbJ1iW4OFIlrG0xil0Z43kyFmic=" Sep 01 00:13:19 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:20 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:20+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:20 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:20+02:00" level=debug msg="completed challenge" Sep 01 00:13:20 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:20+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:20 stefans-speaker go-librespot[4371]: time="2026-09-01T00:13:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:20 stefans-speaker volumio[4001]: info: Updating MyVolumio device info Sep 01 00:13:20 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:20 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:20 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:20 stefans-speaker volumio[4001]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 00:13:20 stefans-speaker volumio[4001]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:20 stefans-speaker volumio[4001]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:23 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 00:13:23 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:23 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:23 stefans-speaker go-librespot[4379]: go-librespot daemon starting... Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="app state loaded" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=info msg="zeroconf server listening on port 39739" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="obtained new client token: AAFMGyb6y3TTlNg17+LS51KMOZ8if6B34kIDwc2Cd4JUC30xHky4DYXNw3iyNkOwE6NAksWcDGYNRGpkOzAMWWoz+mSf/x2jmyw1vJ9tYiDrmGWJmtWhbEOpGloSXBDOq3z63IkbB0rM6tYupq8Gfm9QEg1iykTO6x8qRl9v3MrOvnxKmKwNwcDk7UyzEA05SqzcPhQ23jmHS6aVShQEyooNo9kWGPfXMpqOp9i3JqV49+iXfBYbe8g22pQ=" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="completed challenge" Sep 01 00:13:23 stefans-speaker volumio[4001]: info: MYVOLUMIO: Adding device Sep 01 00:13:23 stefans-speaker volumio[4001]: info: MYVOLUMIO: Evaluating Server Sep 01 00:13:23 stefans-speaker volumio[4001]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=debug msg="new websocket client" Sep 01 00:13:23 stefans-speaker volumio[4001]: info: Connection to go-librespot Websocket established Sep 01 00:13:23 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:23+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:24 stefans-speaker go-librespot[4380]: time="2026-09-01T00:13:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:24 stefans-speaker volumio[4001]: info: Connection to go-librespot Websocket closed Sep 01 00:13:26 stefans-speaker volumio[4001]: info: Setting Geolocation for MyVolumio to eu5 Sep 01 00:13:26 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:26 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:26 stefans-speaker volumio[4001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:26 stefans-speaker volumio[4001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:13:26 stefans-speaker volumio[4001]: info: Getting Spotify volume Sep 01 00:13:26 stefans-speaker volumio[4001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:13:26 stefans-speaker volumio[4001]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:26 stefans-speaker volumio[4001]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 00:13:26 stefans-speaker volumio[4001]: errno: -111, Sep 01 00:13:26 stefans-speaker volumio[4001]: code: 'ECONNREFUSED', Sep 01 00:13:26 stefans-speaker volumio[4001]: syscall: 'connect', Sep 01 00:13:26 stefans-speaker volumio[4001]: address: '127.0.0.1', Sep 01 00:13:26 stefans-speaker volumio[4001]: port: 9879, Sep 01 00:13:26 stefans-speaker volumio[4001]: response: undefined Sep 01 00:13:26 stefans-speaker volumio[4001]: } Sep 01 00:13:26 stefans-speaker volumio[4001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:13:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 00:13:27 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:27 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:27 stefans-speaker go-librespot[4432]: go-librespot daemon starting... Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="app state loaded" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=info msg="zeroconf server listening on port 33319" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="obtained new client token: AAFEnxQakmcWnewOXeXd//S7KeITPh/047ZT14VzmV1ezHCLOuf8c27fpTFiHCTrcqFrCA1PH6dlnY/U9Qt9B1SvsY7z27DB7A/HK/Y1ux3NbkX/3sXkz8h5erPe90WcMMgCoMsCd2aAjvvQvpE8NuqjG7KkLu4MfpsuA5n0sHkXiJogo3xIYMNFkA9By12iM8uqVmjLEVqEIgUnhEIZ2+G8VeapC3g2bHnOEF565jF0IRDKrCLk4D5sG1g=" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=debug msg="completed challenge" Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:27 stefans-speaker sudo[4443]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:27 stefans-speaker sudo[4443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:12' Sep 01 00:13:27 stefans-speaker sudo[4443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:27 stefans-speaker go-librespot[4433]: time="2026-09-01T00:13:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:27 stefans-speaker sudo[4443]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:27 stefans-speaker volumio[4001]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:28 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 00:13:28 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 00:13:28 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:28 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 00:13:28 stefans-speaker systemd[1]: volumio.service: Consumed 32.911s CPU time. Sep 01 00:13:28 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:13:28 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:13:28 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 212. Sep 01 00:13:28 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:13:28 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 00:13:28 stefans-speaker systemd[1]: volumio.service: Consumed 32.911s CPU time. Sep 01 00:13:28 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 00:13:28 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:13:30 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:30 stefans-speaker volumio[4457]: info: ----- Volumio3 ---- Sep 01 00:13:30 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:30 stefans-speaker volumio[4457]: info: ----- System startup ---- Sep 01 00:13:30 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 00:13:31 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:31 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:31 stefans-speaker go-librespot[4481]: go-librespot daemon starting... Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="app state loaded" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:31 stefans-speaker volumio[4457]: info: MYVOLUMIO Environment detected Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Plugin folders cleanup Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning into folder /volumio/app/plugins/ Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category audio_interface Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category miscellanea Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category music_service Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category plugins.json Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category system_controller Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category user_interface Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning into folder /data/plugins/ Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Scanning category music_service Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Plugin folders cleanup completed Sep 01 00:13:31 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:31 stefans-speaker volumio[4457]: info: ----- Core plugins startup ---- Sep 01 00:13:31 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Adding plugin upnp to MyMusic Plugins Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Loading plugins from folder /data/plugins/ Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Loading plugin "system"... Sep 01 00:13:31 stefans-speaker volumio[4457]: info: Loading plugin "appearance"... Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=info msg="zeroconf server listening on port 46017" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="obtained new client token: AAG7lFb/LqPGaiYXk6GITpV4HO/dmMuc1oB4JLho25qKJx1chPXK+tci7B+yrrbY3CA31ZqhNufX6NbB1zDuawh/GrvxYwl53K2bxvWzWsBOKXuYm4k1sPTq/gUuKbeQhwMhh6kT6cxTFcdpRWLNLf8+E+98dDm+E35veuYnwwCR3n4+92eC/mLWZYA9gq3dQPbBNpcl+UVGQD9ghKEvRKwCGt8o6VHV0pRiPwtzXZrf0LgrQgPwdsRaqoQ=" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=debug msg="completed challenge" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:31 stefans-speaker go-librespot[4482]: time="2026-09-01T00:13:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "network"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Refreshing Cached IP Addresses Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "services"... Sep 01 00:13:32 stefans-speaker sudo[4496]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:32 stefans-speaker sudo[4498]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "alsa_controller"... Sep 01 00:13:32 stefans-speaker sudo[4498]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:13:32 stefans-speaker sudo[4496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:13:32 stefans-speaker sudo[4496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:32 stefans-speaker sudo[4498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:32 stefans-speaker sudo[4505]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:32 stefans-speaker sudo[4496]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:32 stefans-speaker sudo[4498]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:32 stefans-speaker sudo[4505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 00:13:32 stefans-speaker sudo[4505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:32 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "wizard"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "networkfs"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Starting Udev Watcher for removable devices Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Ignoring mount for partition: boot Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Ignoring mount for partition: volumio Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Ignoring mount for partition: volumio_data Sep 01 00:13:32 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "volumio_command_line_client"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "upnp"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: [1788214412674] Starting Upmpd Daemon Sep 01 00:13:32 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "my_music"... Sep 01 00:13:32 stefans-speaker volumio[4457]: info: Loading plugin "mpd"... Sep 01 00:13:33 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:33] [connect] Successful connection Sep 01 00:13:33 stefans-speaker volumio[4457]: info: Loading plugin "upnp_browser"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Starting UPNP Browser Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "alarm-clock"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "airplay_emulation"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Starting Shairport Sync Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "last_100"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "webradio"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "i2s_dacs"... Sep 01 00:13:34 stefans-speaker volumio[4457]: info: I2S DAC not set, start Auto-detection Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Loading plugin "volumiodiscovery"... Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** For more information see Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:13:34 stefans-speaker volumio[4457]: *** WARNING *** For more information see Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** For more information see Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:13:34 stefans-speaker node[4457]: *** WARNING *** For more information see Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 00:13:34 stefans-speaker volumio[4457]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 00:13:35 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:13:35 stefans-speaker volumio[4457]: info: Loading plugin "spop"... Sep 01 00:13:35 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 00:13:35 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:35 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:35 stefans-speaker go-librespot[4531]: go-librespot daemon starting... Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="app state loaded" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=info msg="zeroconf server listening on port 43555" Sep 01 00:13:35 stefans-speaker sudo[4505]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="obtained new client token: AAFkATsTnMhqF3lzW92Ks4jzDGZg1EaeTYRYO583n3aPFRmpxblM2r+tTfqC/v8Bqp2sVcSFSC+inw0uuEx3Km64AfC98zsPCBa+4L7bGhDoy8JU8nOOpPOmKpcxkSNdLBn+w2Z/4v7KCeLqtYg2pkWwKBKN5NyAX5TU5wLpofowQyg0LolhC4e8eEb7g3rCp15L7O5goIlxKOIsd3m4xtUg3YkfLRYqfPXAoyrFe2DygW7uiYGBcVxYTos=" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:35 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:35+02:00" level=debug msg="completed challenge" Sep 01 00:13:36 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:36+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:36 stefans-speaker volumio[4457]: info: Loading plugin "ytcr"... Sep 01 00:13:36 stefans-speaker go-librespot[4532]: time="2026-09-01T00:13:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "outputs"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "albumart"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Plugin example_plugin is not enabled Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "inputs"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "updater_comm"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Plugin mpdemulation is not enabled Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "rest_api"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading plugin "websocket"... Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Starting Socket.io Server version 1.7.4 Sep 01 00:13:37 stefans-speaker volumio[4457]: info: Loading i18n strings for locale nl Sep 01 00:13:37 stefans-speaker volumio[4457]: Updating browse sources language Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::initPlayerControls Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:37 stefans-speaker volumio[4457]: Express server listening on port 3000 Sep 01 00:13:37 stefans-speaker volumio[4457]: [Metrics] WebUI: 8s 148.92ms Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::resetVolumioState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::getcurrentVolume Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Cannot read play queue from file Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Volumio Network Manager: Network status updated: 2 Sep 01 00:13:38 stefans-speaker volumio[4541]: Forking 3 albumart workers Sep 01 00:13:38 stefans-speaker volumio[4457]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::pushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::updateTrackBlock Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrackBlock Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::setRepeat null single undefined Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::pushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::setRandom null Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::pushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Setting Device type: Raspberry PI Sep 01 00:13:38 stefans-speaker volumio[4457]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreStateMachine::pushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Completed loading Core Plugins Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Preparing to generate the ALSA configuration file Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Asound.conf file unchanged, so no further update is needed Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Output device has changed, restarting MPD Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Output device has changed, restarting Shairport Sync Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:38 stefans-speaker sudo[4596]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:38 stefans-speaker sudo[4597]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:38 stefans-speaker sudo[4596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:13:38 stefans-speaker sudo[4596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:38 stefans-speaker sudo[4597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:13:38 stefans-speaker sudo[4597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:38 stefans-speaker volumio[4457]: info: ___________ START PLUGINS ___________ Sep 01 00:13:38 stefans-speaker volumio[4457]: info: ControllerMpd::onStart: Initializing MPD Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Creating MPD Configuration file Sep 01 00:13:38 stefans-speaker sudo[4596]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:38 stefans-speaker volumio[4457]: info: [1788214418821] CoreMusicLibrary::Adding element Media Servers Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:38 stefans-speaker volumio[4457]: info: UPNP Browser: Client initialized successfully Sep 01 00:13:38 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 00:13:38 stefans-speaker sudo[4605]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:38 stefans-speaker sudo[4607]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:38 stefans-speaker sudo[4605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:13:38 stefans-speaker sudo[4605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:38 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:13:38 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:13:38 stefans-speaker systemd[1]: mpd.service: Consumed 4.476s CPU time. Sep 01 00:13:38 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:13:38 stefans-speaker sudo[4607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:13:38 stefans-speaker sudo[4607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:38 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:13:38 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:38 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:38 stefans-speaker sudo[4605]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:38 stefans-speaker volumio[4457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:39 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:13:39 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:13:39 stefans-speaker volumio[4457]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:13:39 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:39 stefans-speaker volumio[4457]: info: [1788214419075] CoreMusicLibrary::Adding element Last_100 Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:39 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:13:39 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:13:39 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:13:39 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:39 stefans-speaker volumio[4457]: info: [1788214419098] CoreMusicLibrary::Adding element Webradio Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:39 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:13:39 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:13:39 stefans-speaker volumio[4457]: info: Initializing BBC Radios Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:39 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 00:13:39 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:39 stefans-speaker volumio[4457]: info: Creating Spotify config file Sep 01 00:13:39 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:39 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:39 stefans-speaker go-librespot[4635]: go-librespot daemon starting... Sep 01 00:13:39 stefans-speaker sudo[4621]: root : unable to resolve host stefans-speaker: System error Sep 01 00:13:39 stefans-speaker sudo[4621]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:39 stefans-speaker go-librespot[4636]: time="2026-09-01T00:13:39+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:39 stefans-speaker sudo[4621]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 00:13:39 stefans-speaker sudo[4621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 00:13:39 stefans-speaker sudo[4621]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:39 stefans-speaker go-librespot[4636]: time="2026-09-01T00:13:39+02:00" level=info msg="zeroconf server listening on port 39889" Sep 01 00:13:40 stefans-speaker volumio[4557]: Starting albumart workers Sep 01 00:13:40 stefans-speaker volumio[4457]: info: Volumio Calling Home Sep 01 00:13:40 stefans-speaker volumio[4558]: Starting albumart workers Sep 01 00:13:40 stefans-speaker volumio[4556]: Starting albumart workers Sep 01 00:13:40 stefans-speaker volumio[4457]: info: MPD Permissions set Sep 01 00:13:40 stefans-speaker volumio[4457]: info: MPD Permissions set Sep 01 00:13:40 stefans-speaker volumio[4457]: info: Spotify config file written Sep 01 00:13:40 stefans-speaker volumio[4457]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 00:13:40 stefans-speaker sudo[4651]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:40 stefans-speaker volumio[4457]: info: Volumio called home Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker sudo[4651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 00:13:40 stefans-speaker sudo[4651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 00:13:40 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 00:13:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:41 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:41 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:13:41 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:41 stefans-speaker go-librespot[4660]: go-librespot daemon starting... Sep 01 00:13:41 stefans-speaker sudo[4651]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:41 stefans-speaker volumio[4457]: info: No need to fix Spotify hosts Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="app state loaded" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=info msg="zeroconf server listening on port 40093" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="obtained new client token: AAFRRvqFZIyZ7oquzFTlAgjZg2mEDH4jhKOi56R6Ja6gDkBcrQbMrtDjXIus7kMyGkgCjrJYPVDZc5QGe30lqrGcPJGubL7UyneScpzbpHc7phpZvSmxxHtsFwEJWFyS7H7wxVmKojS7qvWP9IACzsyIOyn6aMEO7tcYwLGlIlUyJvV4t0pUrA7QO/6Hgr6qvTS8ddZsak84OOCnLDcpNmAXRqH+wNKd2lHLwGCDbFOXsPnJCtGhapN+2ZA=" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=debug msg="completed challenge" Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:41 stefans-speaker volumio[4457]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:13:41 stefans-speaker volumio[4457]: SPOTIFY: BQC82xRBpCDoINts8T7Xzbstqk1FTN8RM03or-ME5s7oU25kWiJ47LEYE-wdYnr6llsfgXO7nWWPzfgTusOnfWfkx3Hl_li-Kl1D77P-N_IV3VVmZSddVorurr7hMbJKn99kZShbPo-WFexy78BOZIA5MOvY-7BeSWfcPNS7niYRKsl0dIf3_pqlp95mGR9yYwpk3HA_QmvWcL35rmysnq_jGgr5RjTDU9F7t9ke601WyCznTy7uNlJxBaXKaVxPOUAJm-jKQ0SzaDXk70_XWUt4w11AHf_2Im8FZTjB6G9t9bfFLjxv9vfnP0yRq9w Sep 01 00:13:41 stefans-speaker volumio[4457]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:13:41 stefans-speaker volumio[4457]: info: New Spotify access token = BQC82xRBpCDoINts8T7Xzbstqk1FTN8RM03or-ME5s7oU25kWiJ47LEYE-wdYnr6llsfgXO7nWWPzfgTusOnfWfkx3Hl_li-Kl1D77P-N_IV3VVmZSddVorurr7hMbJKn99kZShbPo-WFexy78BOZIA5MOvY-7BeSWfcPNS7niYRKsl0dIf3_pqlp95mGR9yYwpk3HA_QmvWcL35rmysnq_jGgr5RjTDU9F7t9ke601WyCznTy7uNlJxBaXKaVxPOUAJm-jKQ0SzaDXk70_XWUt4w11AHf_2Im8FZTjB6G9t9bfFLjxv9vfnP0yRq9w Sep 01 00:13:41 stefans-speaker volumio[4457]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 00:13:41 stefans-speaker volumio[4457]: info: Starting Shairport Sync Sep 01 00:13:41 stefans-speaker volumio[4457]: info: Starting Shairport Sync Sep 01 00:13:41 stefans-speaker go-librespot[4665]: time="2026-09-01T00:13:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:41 stefans-speaker volumio[4457]: info: Starting Shairport Sync Sep 01 00:13:41 stefans-speaker sudo[4691]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker sudo[4693]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker sudo[4691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:42 stefans-speaker sudo[4695]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker sudo[4693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:42 stefans-speaker sudo[4693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker sudo[4691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker sudo[4695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:13:42 stefans-speaker sudo[4695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:42 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:13:42 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:13:42 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:42 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.890s CPU time. Sep 01 00:13:42 stefans-speaker volumio[4457]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 00:13:42 stefans-speaker volumio[4457]: info: Spotify Successfully logged in Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:13:42 stefans-speaker volumio[4457]: info: [1788214422274] CoreMusicLibrary::Adding element Spotify Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:13:42 stefans-speaker volumio[4457]: Cannot find translation for source Spotify Sep 01 00:13:42 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:13:42 stefans-speaker sudo[4695]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker sudo[4691]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker sudo[4693]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker volumio[4457]: info: Shairport-Sync Started Sep 01 00:13:42 stefans-speaker volumio[4457]: Error adding Membership: Error: addMembership EINVAL Sep 01 00:13:42 stefans-speaker volumio[4457]: info: Shairport-Sync Started Sep 01 00:13:42 stefans-speaker volumio[4457]: info: Shairport-Sync Started Sep 01 00:13:42 stefans-speaker volumio[4457]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:13:42 stefans-speaker sudo[4730]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker volumio[4457]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreStateMachine::pushState Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:42 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioPushState Sep 01 00:13:42 stefans-speaker sudo[4730]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:13:42 stefans-speaker sudo[4730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker sudo[4732]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker sudo[4732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:13:42 stefans-speaker sudo[4730]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker sudo[4732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker sudo[4732]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker sudo[4737]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:42 stefans-speaker sudo[4737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 00:13:42 stefans-speaker sudo[4737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:42 stefans-speaker sudo[4737]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:42 stefans-speaker volumio[4457]: info: Upmpdcli Daemon Started Sep 01 00:13:43 stefans-speaker mpd[4644]: 2026-09-01T00:13:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 00:13:43 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 00:13:43 stefans-speaker sudo[4607]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:43 stefans-speaker sudo[4597]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:44 stefans-speaker volumio[4457]: error: MPD error: The expression evaluated to a falsy value: Sep 01 00:13:44 stefans-speaker volumio[4457]: assert.ok(self.idling) Sep 01 00:13:44 stefans-speaker volumio[4457]: error: The expression evaluated to a falsy value: Sep 01 00:13:44 stefans-speaker volumio[4457]: assert.ok(self.idling) Sep 01 00:13:44 stefans-speaker volumio[4457]: info: MPD running with PID4644 Sep 01 00:13:44 stefans-speaker volumio[4457]: ,establishing connection Sep 01 00:13:44 stefans-speaker volumio[4457]: error: updateQueue error: null Sep 01 00:13:44 stefans-speaker volumio[4457]: info: Completed starting Core Plugins Sep 01 00:13:44 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:44 stefans-speaker volumio[4457]: info: ----- MyVolumio plugins startup ---- Sep 01 00:13:44 stefans-speaker volumio[4457]: info: ------------------------------------------- Sep 01 00:13:44 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 00:13:44 stefans-speaker volumio[4457]: error: updateQueue error: null Sep 01 00:13:44 stefans-speaker volumio[4457]: info: go-librespot daemon successfully initialized Sep 01 00:13:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 00:13:45 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:45 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:45 stefans-speaker go-librespot[4745]: go-librespot daemon starting... Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="app state loaded" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=info msg="zeroconf server listening on port 39477" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="obtained new client token: AAEjK2bapSDsHrkD2Xdd5JP8PtgR9VTpLS0kCRElceawj1I7a55UBK0Yy6VS2j+37DU8b8LMOCMh2zWrhZfIH3iwQB9qH6x3FOWoXAZoZzPT12pFHFDCSQ/EM8hw9Wi9FXNvLdr/QaRO6EKXnU9cg72gxC/k0aEvn2kQaC+ost1XpdZwrE7MxLd3C8w/47QnuBmXIcIfJHIaA1OejWbT+e1GBqRSjFVe7M6ymXuhWtntltuFW1iVblw40Es=" Sep 01 00:13:45 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:45] [connect] Successful connection Sep 01 00:13:45 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:13:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788214425 101 Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:45 stefans-speaker volumio[4457]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=debug msg="completed challenge" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:45 stefans-speaker go-librespot[4746]: time="2026-09-01T00:13:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:46 stefans-speaker sudo[4756]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:46 stefans-speaker sudo[4756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:13:46 stefans-speaker sudo[4756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:46 stefans-speaker sudo[4758]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:46 stefans-speaker sudo[4758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:13:46 stefans-speaker sudo[4758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:47 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 00:13:47 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 00:13:47 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 00:13:47 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 00:13:47 stefans-speaker mpd_monitor.sh[4761]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 00:13:47 stefans-speaker sudo[4756]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:47 stefans-speaker sudo[4758]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:47 stefans-speaker volumio[4457]: info: Successfully started MPD Monitor Sep 01 00:13:47 stefans-speaker volumio[4457]: info: Successfully started MPD Monitor Sep 01 00:13:47 stefans-speaker volumio[4457]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:47 stefans-speaker volumio[4457]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:48 stefans-speaker volumio[4457]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 00:13:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 00:13:49 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:49 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:49 stefans-speaker go-librespot[4765]: go-librespot daemon starting... Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="app state loaded" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=info msg="zeroconf server listening on port 43123" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="obtained new client token: AAEPOXGrepYS0ZMjzCccIsjKTx4wi8sLL1XwKrg3PchlQRDyatFm/3x/GdPyh8rZQXxwFnDlIlVcCT4AD2HIb09bTHFZc6bNVx68BvJlqGTWntaHGKTIOX9vMbv5pv+HaIfh6DrzZDyFeUA3+5KT4FgsHScvOpOrlxwDH51eoJbmVJ1uVRq5yIGCuzDB1ZI1L3x3om3T5IMxsY8K4XOmSGpaZ76NMgAEFI8JiNxT4CfzosP9bI58Y8dpS/M=" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=debug msg="completed challenge" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:49 stefans-speaker go-librespot[4766]: time="2026-09-01T00:13:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:50 stefans-speaker volumio[4457]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:50 stefans-speaker volumio[4457]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 00:13:52 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 00:13:53 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 00:13:53 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:53 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:13:53 stefans-speaker go-librespot[4787]: go-librespot daemon starting... Sep 01 00:13:53 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:53+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:13:53 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:53+02:00" level=debug msg="app state loaded" Sep 01 00:13:53 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:13:53 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 00:13:53 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 00:13:53 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:53 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:53 stefans-speaker volumio[4457]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 00:13:53 stefans-speaker volumio[4457]: info: MyVolumio login type: Token Sep 01 00:13:53 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 00:13:53 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 00:13:54 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 00:13:54 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 00:13:54 stefans-speaker volumio[4457]: info: Streaming services startup Sep 01 00:13:54 stefans-speaker volumio[4457]: info: Starting Streaming Daemon Sep 01 00:13:54 stefans-speaker volumio[4457]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 00:13:54 stefans-speaker sudo[4795]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:54 stefans-speaker sudo[4795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:13:54 stefans-speaker sudo[4795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:54 stefans-speaker sudo[4795]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:54 stefans-speaker volumio[4457]: info: Initializing connection to go-librespot Websocket Sep 01 00:13:54 stefans-speaker volumio[4457]: error: Cannot start Volumio Streaming Daemon Sep 01 00:13:54 stefans-speaker volumio[4457]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:13:54 stefans-speaker volumio[4457]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:54 stefans-speaker volumio[4457]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:13:54 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:54+02:00" level=debug msg="new websocket client" Sep 01 00:13:54 stefans-speaker volumio[4457]: info: Connection to go-librespot Websocket established Sep 01 00:13:55 stefans-speaker volumio[4457]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 00:13:55 stefans-speaker volumio[4457]: info: MyVolumio token set successfully Sep 01 00:13:55 stefans-speaker volumio[4457]: info: MYVOLUMIO: Adding device Sep 01 00:13:55 stefans-speaker volumio[4457]: info: MYVOLUMIO: Evaluating Server Sep 01 00:13:55 stefans-speaker volumio[4457]: info: MyVolumio status changed Sep 01 00:13:55 stefans-speaker volumio[4457]: info: Streaming services startup Sep 01 00:13:55 stefans-speaker volumio[4457]: info: Starting Streaming Daemon Sep 01 00:13:55 stefans-speaker volumio[4457]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 00:13:55 stefans-speaker volumio[4457]: info: Removing audio output: Sep 01 00:13:55 stefans-speaker volumio[4457]: info: Stoppping Tunnel 1 Sep 01 00:13:55 stefans-speaker sudo[4823]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:55 stefans-speaker sudo[4823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:13:55 stefans-speaker sudo[4823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:55 stefans-speaker sudo[4825]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:55 stefans-speaker sudo[4825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 00:13:55 stefans-speaker sudo[4825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker sudo[4823]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:56 stefans-speaker volumio[4457]: error: Cannot start Volumio Streaming Daemon Sep 01 00:13:56 stefans-speaker volumio[4457]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:13:56 stefans-speaker volumio[4457]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:13:56 stefans-speaker volumio[4457]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:13:56 stefans-speaker sudo[4825]: pam_unix(sudo:session): session closed for user root Sep 01 00:13:56 stefans-speaker volumio[4457]: info: Remote SSH Stopped Sep 01 00:13:56 stefans-speaker volumio[4457]: info: Setting Geolocation for MyVolumio to eu8 Sep 01 00:13:56 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:56 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:56 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:56 stefans-speaker volumio[4457]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:13:57 stefans-speaker volumio[4457]: info: Updating MyVolumio device info Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:13:57 stefans-speaker volumio[4457]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 00:13:57 stefans-speaker volumio[4457]: info: Getting Spotify volume Sep 01 00:13:57 stefans-speaker volumio[4457]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:57 stefans-speaker volumio[4457]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 00:13:57 stefans-speaker volumio[4457]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 00:13:57 stefans-speaker volumio[4457]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 00:13:57 stefans-speaker volumio[4457]: info: Aligning Spotify Volume to Volumio Volume Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CoreCommandRouter::volumioGetState Sep 01 00:13:57 stefans-speaker volumio[4457]: info: CorePlayQueue::getTrack 0 Sep 01 00:13:57 stefans-speaker volumio[4457]: info: Setting Spotify Volume from Volumio: 70 Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=info msg="zeroconf server listening on port 46191" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="obtained new client token: AAHtF0g2TLEmvsr/YFxERYfbnkK52s5VNSTyvEp25ddLC8sO6G2mOmRqvuCNyxiq+0KG5UqEz9tquEzOG2kfzbxPmsfU+Cy7aPzJz71ZAgf4GU2JMeOKW9pmcptIFnxLEj6RxvkMz0Y3xC+7RqzW/0teUNJLGnb+rUHF/ngN3guKOnaZX2I+w8R0u9+1FzkhN73d13muwa9FHCHLsy3+mkqltO5Wmb0oq8+9LNbcCGvPmB6LWXbdVQ1DWvM=" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="completed keyexchange" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=debug msg="completed challenge" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:13:58 stefans-speaker go-librespot[4788]: time="2026-09-01T00:13:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:13:58 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:13:58 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:13:58 stefans-speaker volumio[4457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:13:58 stefans-speaker volumio[4457]: Error: socket hang up Sep 01 00:13:58 stefans-speaker volumio[4457]: at connResetException (node:internal/errors:720:14) Sep 01 00:13:58 stefans-speaker volumio[4457]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 00:13:58 stefans-speaker volumio[4457]: at Socket.emit (node:events:526:35) Sep 01 00:13:58 stefans-speaker volumio[4457]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 00:13:58 stefans-speaker volumio[4457]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 00:13:58 stefans-speaker volumio[4457]: code: 'ECONNRESET', Sep 01 00:13:58 stefans-speaker volumio[4457]: response: undefined Sep 01 00:13:58 stefans-speaker volumio[4457]: } Sep 01 00:13:58 stefans-speaker volumio[4457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:13:59 stefans-speaker sudo[4842]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:13:59 stefans-speaker sudo[4842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:12' Sep 01 00:13:59 stefans-speaker sudo[4842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:13:59 stefans-speaker sudo[4842]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:00 stefans-speaker volumio[4457]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:14:00 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:00 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:14:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 00:14:00 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:14:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 00:14:00 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 00:14:00 stefans-speaker systemd[1]: volumio.service: Consumed 32.789s CPU time. Sep 01 00:14:00 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:14:00 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:14:01 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 213. Sep 01 00:14:01 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:14:01 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 00:14:01 stefans-speaker systemd[1]: volumio.service: Consumed 32.789s CPU time. Sep 01 00:14:01 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 00:14:01 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:14:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 00:14:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:02 stefans-speaker go-librespot[4873]: go-librespot daemon starting... Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="app state loaded" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=info msg="zeroconf server listening on port 34461" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="obtained new client token: AAGdVaEDL9ZXE9mt21Yx+mymBIjeRwTA15+CpWoJViA8RBGiDRi2kGSKuY7Gk0zdDP4/12ZiX8ftH2cqh/MFRcxL+cvbmoh1g6rSHdNggsppcMfPU6ntsfZdbnT9RBfH2a/vAUX192hTGXRbYiYjiK3PSOA5nICu3Kpe7/Mn35GkComN14VqLIWru+yL+KtI6hzPQ2aP2W2/Mhk/F9pNIlmsBBrjC7FdRawsO/RnxYZTJhdu5Iz8cml4cwE=" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=debug msg="completed challenge" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:02 stefans-speaker go-librespot[4874]: time="2026-09-01T00:14:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:03 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:03 stefans-speaker volumio[4858]: info: ----- Volumio3 ---- Sep 01 00:14:03 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:03 stefans-speaker volumio[4858]: info: ----- System startup ---- Sep 01 00:14:03 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:03 stefans-speaker volumio[4858]: info: MYVOLUMIO Environment detected Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Plugin folders cleanup Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning into folder /volumio/app/plugins/ Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category audio_interface Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category miscellanea Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category music_service Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category plugins.json Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category system_controller Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category user_interface Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning into folder /data/plugins/ Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Scanning category music_service Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Plugin folders cleanup completed Sep 01 00:14:04 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:04 stefans-speaker volumio[4858]: info: ----- Core plugins startup ---- Sep 01 00:14:04 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Adding plugin upnp to MyMusic Plugins Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Loading plugins from folder /data/plugins/ Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Loading plugin "system"... Sep 01 00:14:04 stefans-speaker volumio[4858]: info: Loading plugin "appearance"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "network"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Refreshing Cached IP Addresses Sep 01 00:14:05 stefans-speaker sudo[4894]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:05 stefans-speaker sudo[4896]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "services"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "alsa_controller"... Sep 01 00:14:05 stefans-speaker sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:14:05 stefans-speaker sudo[4894]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:14:05 stefans-speaker sudo[4896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:05 stefans-speaker sudo[4894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:05 stefans-speaker sudo[4903]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:05 stefans-speaker sudo[4896]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:05 stefans-speaker sudo[4894]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:05 stefans-speaker sudo[4903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 00:14:05 stefans-speaker sudo[4903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:05 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "wizard"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "networkfs"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Starting Udev Watcher for removable devices Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Ignoring mount for partition: boot Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Ignoring mount for partition: volumio Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Ignoring mount for partition: volumio_data Sep 01 00:14:05 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "volumio_command_line_client"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "upnp"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: [1788214445379] Starting Upmpd Daemon Sep 01 00:14:05 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "my_music"... Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "mpd"... Sep 01 00:14:05 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:14:05] [connect] Successful connection Sep 01 00:14:05 stefans-speaker volumio[4858]: info: Loading plugin "upnp_browser"... Sep 01 00:14:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 00:14:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:06 stefans-speaker go-librespot[4927]: go-librespot daemon starting... Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=debug msg="app state loaded" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:06 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:06+02:00" level=info msg="zeroconf server listening on port 37255" Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=debug msg="obtained new client token: AAHmMhLlObIHWpba5lM8o3+Uz2D8dffsh78KJC52+EB/BGBv0ipVY7IsTJyM5b8TylV0YQcDWe5XR3bD46zPzTEZF/k08+mKyz0UpNzQ1VizviulDf224E9mERb0ir/N5+9bnuWcPRxcB95F47jvsig/Ery1FZ18w9jUFAFRQ2WQXoQj9CRmMbVVaUQRJ5lTU+KXVW630dLdwdHqt5fYAPeVKusAqBQPYJcJkI9wgTfQidmX+f8/Ui0isnA=" Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=debug msg="completed challenge" Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Starting UPNP Browser Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "alarm-clock"... Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "airplay_emulation"... Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Starting Shairport Sync Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "last_100"... Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "webradio"... Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "i2s_dacs"... Sep 01 00:14:07 stefans-speaker volumio[4858]: info: I2S DAC not set, start Auto-detection Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "volumiodiscovery"... Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** For more information see Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:14:07 stefans-speaker volumio[4858]: *** WARNING *** For more information see Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** For more information see Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:14:07 stefans-speaker node[4858]: *** WARNING *** For more information see Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 00:14:07 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:14:07 stefans-speaker volumio[4858]: info: Loading plugin "spop"... Sep 01 00:14:07 stefans-speaker go-librespot[4928]: time="2026-09-01T00:14:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:08 stefans-speaker sudo[4903]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:08 stefans-speaker volumio[4858]: info: Loading plugin "ytcr"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "outputs"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "albumart"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Plugin example_plugin is not enabled Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "inputs"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "updater_comm"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Plugin mpdemulation is not enabled Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "rest_api"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading plugin "websocket"... Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Starting Socket.io Server version 1.7.4 Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Loading i18n strings for locale nl Sep 01 00:14:10 stefans-speaker volumio[4858]: Updating browse sources language Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::initPlayerControls Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: Express server listening on port 3000 Sep 01 00:14:10 stefans-speaker volumio[4858]: [Metrics] WebUI: 8s 50.12ms Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreStateMachine::resetVolumioState Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreStateMachine::getcurrentVolume Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Cannot read play queue from file Sep 01 00:14:10 stefans-speaker volumio[4858]: info: Volumio Network Manager: Network status updated: 2 Sep 01 00:14:10 stefans-speaker volumio[4940]: Forking 3 albumart workers Sep 01 00:14:10 stefans-speaker volumio[4858]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreStateMachine::pushState Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioPushState Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreStateMachine::updateTrackBlock Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrackBlock Sep 01 00:14:10 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:14:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 00:14:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:11 stefans-speaker go-librespot[4984]: go-librespot daemon starting... Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="app state loaded" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:11 stefans-speaker volumio[4858]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreStateMachine::pushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioPushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreStateMachine::setRepeat null single undefined Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreStateMachine::pushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioPushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreStateMachine::setRandom null Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreStateMachine::pushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioPushState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Setting Device type: Raspberry PI Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Completed loading Core Plugins Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Preparing to generate the ALSA configuration file Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioGetState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioGetState Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Asound.conf file unchanged, so no further update is needed Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Output device has changed, restarting MPD Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Output device has changed, restarting Shairport Sync Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:11 stefans-speaker sudo[5002]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:11 stefans-speaker sudo[5003]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:11 stefans-speaker sudo[5002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:14:11 stefans-speaker sudo[5002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:11 stefans-speaker sudo[5003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:14:11 stefans-speaker sudo[5003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:11 stefans-speaker sudo[5002]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: ___________ START PLUGINS ___________ Sep 01 00:14:11 stefans-speaker volumio[4858]: info: ControllerMpd::onStart: Initializing MPD Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Creating MPD Configuration file Sep 01 00:14:11 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=info msg="zeroconf server listening on port 33773" Sep 01 00:14:11 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:14:11 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:14:11 stefans-speaker systemd[1]: mpd.service: Consumed 4.477s CPU time. Sep 01 00:14:11 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:14:11 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:14:11 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:14:11 stefans-speaker sudo[5012]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:14:11 stefans-speaker volumio[4858]: info: [1788214451633] CoreMusicLibrary::Adding element Media Servers Sep 01 00:14:11 stefans-speaker sudo[5012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:14:11 stefans-speaker sudo[5014]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:11 stefans-speaker sudo[5012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:11 stefans-speaker sudo[5014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:14:11 stefans-speaker sudo[5014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:11 stefans-speaker volumio[4858]: info: UPNP Browser: Client initialized successfully Sep 01 00:14:11 stefans-speaker sudo[5012]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:11 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:14:11 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="obtained new client token: AAHP3xd4hJT+P2XhRK3V+vdxSkUgqY6u0t88dU9SVu/zTrP2XnbIW5WkmHnE9A6UGkB/bls3qizRXDO5pLGWwLZoulTxhbHLZwvMSqGukHg12XAT61go9bjQnBRpnDlcdV/PTJnmWtBRcxMjVvxItVHipXR1i1x+ejIa056Q+S9JfZPyt6s3uSVcAqI9n9wBD49vbPsa//jS5oNnVlYn3zvwomMc9f3t+1/optQ0AE/ZxojNd9J5ZVnYwKw=" Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:11 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:14:11 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:11 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:14:11 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:14:11 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:11 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:14:11 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:14:11 stefans-speaker volumio[4858]: info: [1788214451811] CoreMusicLibrary::Adding element Last_100 Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:14:11 stefans-speaker volumio[4858]: info: [1788214451828] CoreMusicLibrary::Adding element Webradio Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=debug msg="completed challenge" Sep 01 00:14:11 stefans-speaker volumio[4858]: info: Initializing BBC Radios Sep 01 00:14:11 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:11+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:14:11 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:12 stefans-speaker go-librespot[4989]: time="2026-09-01T00:14:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:12 stefans-speaker volumio[4858]: info: Creating Spotify config file Sep 01 00:14:12 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:12 stefans-speaker sudo[5028]: root : unable to resolve host stefans-speaker: System error Sep 01 00:14:12 stefans-speaker sudo[5028]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:14:12 stefans-speaker sudo[5028]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 00:14:12 stefans-speaker sudo[5028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 00:14:12 stefans-speaker sudo[5028]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:12 stefans-speaker volumio[4858]: info: Volumio Calling Home Sep 01 00:14:12 stefans-speaker volumio[4957]: Starting albumart workers Sep 01 00:14:13 stefans-speaker volumio[4956]: Starting albumart workers Sep 01 00:14:13 stefans-speaker volumio[4955]: Starting albumart workers Sep 01 00:14:13 stefans-speaker volumio[4858]: info: MPD Permissions set Sep 01 00:14:13 stefans-speaker volumio[4858]: info: MPD Permissions set Sep 01 00:14:13 stefans-speaker volumio[4858]: info: Volumio called home Sep 01 00:14:13 stefans-speaker volumio[4858]: info: Spotify config file written Sep 01 00:14:13 stefans-speaker sudo[5048]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:13 stefans-speaker sudo[5048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 00:14:13 stefans-speaker sudo[5048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:13 stefans-speaker volumio[4858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:13 stefans-speaker go-librespot[5055]: go-librespot daemon starting... Sep 01 00:14:13 stefans-speaker sudo[5048]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:14:13 stefans-speaker volumio[4858]: info: No need to fix Spotify hosts Sep 01 00:14:13 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:13 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=debug msg="app state loaded" Sep 01 00:14:13 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:13 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 00:14:13 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=info msg="zeroconf server listening on port 37609" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=debug msg="obtained new client token: AAFNWg8EBkeXxMyJEMkivoeSa9VveOS0fsYX+0cJo5/LbvZAkbo4bUvSQv99NC0wWziOgnQeFx7d7wb1b2FVgwxIbObVLMF5pGWbLk0+kVc53Hi+RQehs55YFUfjAioAIMz5r2nAUZhXWlTtKd0LIvbzPzc24h+YX/6Kugm8VRpkYF6ddyYCoVBQRDI4r0nFZPsadVcIQGT9ZTQqrw6XkDoc7VEdhFkOv8b+I1rngb2P5pnbtTMJ8scB" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=debug msg="completed challenge" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:14 stefans-speaker go-librespot[5061]: time="2026-09-01T00:14:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:14 stefans-speaker volumio[4858]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:14:14 stefans-speaker volumio[4858]: SPOTIFY: BQD5NBQxyKNzL_rc8jw2Vsk3btYKXgxigpdNmtYcdrk6SjvgE8NSaNqYNdhoGBmeMJO5yLvdW_F_k9Oy6sTWranIFXHdJFBufBdVip-dC4a0LXNTfGcWkUB0XnM1w7LwqszbqneIdEPHjSPSwnIhIgsIyZh6xdgXg7oURRmjzFQivRce6ypLYzLHRh5j6hLwrr3XRcDkE2RfXd0VOmOJiDBKo7lz8kn0DrGBXNch71mJ-txO-iVBhusvX0rSoIYiZjuIyZHN-mSi4hYC84F7rAZlH74E1YIItxhb6rFLKP4xtsWXKGlkq0EbrR8VNpw Sep 01 00:14:14 stefans-speaker volumio[4858]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:14:14 stefans-speaker volumio[4858]: info: New Spotify access token = BQD5NBQxyKNzL_rc8jw2Vsk3btYKXgxigpdNmtYcdrk6SjvgE8NSaNqYNdhoGBmeMJO5yLvdW_F_k9Oy6sTWranIFXHdJFBufBdVip-dC4a0LXNTfGcWkUB0XnM1w7LwqszbqneIdEPHjSPSwnIhIgsIyZh6xdgXg7oURRmjzFQivRce6ypLYzLHRh5j6hLwrr3XRcDkE2RfXd0VOmOJiDBKo7lz8kn0DrGBXNch71mJ-txO-iVBhusvX0rSoIYiZjuIyZHN-mSi4hYC84F7rAZlH74E1YIItxhb6rFLKP4xtsWXKGlkq0EbrR8VNpw Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Starting Shairport Sync Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Starting Shairport Sync Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Starting Shairport Sync Sep 01 00:14:14 stefans-speaker sudo[5088]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:14 stefans-speaker sudo[5090]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:14 stefans-speaker sudo[5088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:14:14 stefans-speaker sudo[5088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:14 stefans-speaker sudo[5090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:14:14 stefans-speaker sudo[5090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:14 stefans-speaker sudo[5092]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:14 stefans-speaker sudo[5092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:14:14 stefans-speaker sudo[5092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:14 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:14:14 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:14:14 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:14:14 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.844s CPU time. Sep 01 00:14:14 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:14:14 stefans-speaker sudo[5088]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:14 stefans-speaker sudo[5090]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:14 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:14:14 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:14:14 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Shairport-Sync Started Sep 01 00:14:14 stefans-speaker volumio[4858]: Error adding Membership: Error: addMembership EINVAL Sep 01 00:14:14 stefans-speaker volumio[4858]: info: Shairport-Sync Started Sep 01 00:14:14 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioGetState Sep 01 00:14:14 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:15 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:14:15 stefans-speaker sudo[5092]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:15 stefans-speaker volumio[4858]: info: Shairport-Sync Started Sep 01 00:14:15 stefans-speaker volumio[4858]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 00:14:15 stefans-speaker volumio[4858]: info: Spotify Successfully logged in Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:14:15 stefans-speaker volumio[4858]: info: [1788214455124] CoreMusicLibrary::Adding element Spotify Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:14:15 stefans-speaker volumio[4858]: Cannot find translation for source Spotify Sep 01 00:14:15 stefans-speaker sudo[5127]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:15 stefans-speaker sudo[5127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:14:15 stefans-speaker sudo[5129]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:15 stefans-speaker sudo[5129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:14:15 stefans-speaker sudo[5129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:15 stefans-speaker sudo[5127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:15 stefans-speaker sudo[5129]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:15 stefans-speaker sudo[5127]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:15 stefans-speaker volumio[4858]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 00:14:15 stefans-speaker sudo[5133]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:15 stefans-speaker sudo[5133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 00:14:15 stefans-speaker sudo[5133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:14:15 stefans-speaker sudo[5133]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:15 stefans-speaker volumio[4858]: info: Upmpdcli Daemon Started Sep 01 00:14:15 stefans-speaker volumio[4858]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioGetState Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreStateMachine::pushState Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CorePlayQueue::getTrack 0 Sep 01 00:14:15 stefans-speaker volumio[4858]: info: CoreCommandRouter::volumioPushState Sep 01 00:14:16 stefans-speaker mpd[5043]: 2026-09-01T00:14:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 00:14:16 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 00:14:16 stefans-speaker sudo[5003]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:16 stefans-speaker sudo[5014]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:16 stefans-speaker volumio[4858]: error: MPD error: The expression evaluated to a falsy value: Sep 01 00:14:16 stefans-speaker volumio[4858]: assert.ok(self.idling) Sep 01 00:14:16 stefans-speaker volumio[4858]: error: The expression evaluated to a falsy value: Sep 01 00:14:16 stefans-speaker volumio[4858]: assert.ok(self.idling) Sep 01 00:14:16 stefans-speaker volumio[4858]: info: MPD running with PID5043 Sep 01 00:14:16 stefans-speaker volumio[4858]: ,establishing connection Sep 01 00:14:16 stefans-speaker volumio[4858]: error: updateQueue error: null Sep 01 00:14:16 stefans-speaker volumio[4858]: info: Completed starting Core Plugins Sep 01 00:14:16 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:16 stefans-speaker volumio[4858]: info: ----- MyVolumio plugins startup ---- Sep 01 00:14:16 stefans-speaker volumio[4858]: info: ------------------------------------------- Sep 01 00:14:16 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 00:14:16 stefans-speaker volumio[4858]: error: updateQueue error: null Sep 01 00:14:17 stefans-speaker volumio[4858]: info: go-librespot daemon successfully initialized Sep 01 00:14:17 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 00:14:17 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:17 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:17 stefans-speaker go-librespot[5143]: go-librespot daemon starting... Sep 01 00:14:17 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:17+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:17 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:17+02:00" level=debug msg="app state loaded" Sep 01 00:14:17 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=info msg="zeroconf server listening on port 40715" Sep 01 00:14:18 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:14:18] [connect] Successful connection Sep 01 00:14:18 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:14:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788214458 101 Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="obtained new client token: AAGOZEq11Yk+Z+VjJKOfnALfdzTomZiZLuaMn+CbCP9aDBFAcQMW2dFqDtXZCxAltWJfBDV+haFkD0+geVtbpiO5EfzuCoQqyiv761O1A+T8ndJXv6q1RxSnJ3srs0Oahv9n/XYTGYnlpT3P9fK1dY/r+tdeH9vEm4fY32vwmHBcT9Pu2f9eqINFPmCoE41bu8S+4rsPqaH33sMNok/XuGFYdm6in+uVMOD56O4Gr0Eq0AB8Ai/KydBA" Sep 01 00:14:18 stefans-speaker volumio[4858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=debug msg="completed challenge" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:18 stefans-speaker go-librespot[5144]: time="2026-09-01T00:14:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:19 stefans-speaker sudo[5155]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:19 stefans-speaker sudo[5157]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:19 stefans-speaker sudo[5155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:14:19 stefans-speaker sudo[5155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:19 stefans-speaker sudo[5157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:14:19 stefans-speaker sudo[5157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:19 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 00:14:19 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 00:14:19 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 00:14:19 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 00:14:19 stefans-speaker sudo[5157]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:19 stefans-speaker mpd_monitor.sh[5160]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 00:14:19 stefans-speaker sudo[5155]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:19 stefans-speaker volumio[4858]: info: Successfully started MPD Monitor Sep 01 00:14:19 stefans-speaker volumio[4858]: info: Successfully started MPD Monitor Sep 01 00:14:20 stefans-speaker volumio[4858]: info: Initializing connection to go-librespot Websocket Sep 01 00:14:20 stefans-speaker volumio[4858]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:14:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 00:14:21 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:21 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:21 stefans-speaker go-librespot[5165]: go-librespot daemon starting... Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=debug msg="app state loaded" Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:21 stefans-speaker volumio[4858]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:21 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:21+02:00" level=info msg="zeroconf server listening on port 35103" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=debug msg="obtained new client token: AAGHUsEjIYYrTWuUH1/HHEZ+m3fnySg442y/US5koIN/OePM5YTMiKzNp+qfDBbRTywDZGo6HGpEh4x78J7znS3rVO3KNI2PZiB+llRbm4Wf6+pvy9F0LaoS2neaDk96W1WlbsZ0L8j6AI8nsyvQsQCT6kaTJTKjNyX/dqSZrlMyTSGq+NIeYEQ+FrCL+K9JH5ZJIqp/L5pGNeYGwLoDqaLt0jZM4KEMMwJAxrL39qzHzwOuTBgBHjxkFvk=" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=debug msg="completed challenge" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:22 stefans-speaker go-librespot[5166]: time="2026-09-01T00:14:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:22 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:22 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:23 stefans-speaker volumio[4858]: info: Initializing connection to go-librespot Websocket Sep 01 00:14:23 stefans-speaker volumio[4858]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 00:14:25 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 00:14:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 00:14:25 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:25 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:25 stefans-speaker go-librespot[5188]: go-librespot daemon starting... Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=debug msg="app state loaded" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 01 00:14:25 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:25+02:00" level=info msg="zeroconf server listening on port 40053" Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=debug msg="obtained new client token: AAGroQY8iYG/AEjDB1UorF3WYx+FDA/ZuyGMQoFzNEWWbyxK9gi2LqOd/MbXJCJOzkHuCOwH+3ZP9CLpAIKsn9v84pb31hv03Fz9R6Zr7AMhR5xs9n+AWxAgCbn1V73vXyMuA2YpoNFJbFm6UuUUmCJGEiEPkD3SPhEIblYssqIbo5ofUo7DLAGjzYbxlkaDmoW5aeUdD9HV6A/QE49RXyBILjWXdjm/KAwVjHYWw0N84Ad6HHxD2t0W" Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=debug msg="completed challenge" Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:26 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 00:14:26 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 00:14:26 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:26 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:26 stefans-speaker volumio[4858]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 00:14:26 stefans-speaker volumio[4858]: info: MyVolumio login type: Token Sep 01 00:14:26 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 00:14:26 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 00:14:26 stefans-speaker go-librespot[5189]: time="2026-09-01T00:14:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:26 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:26 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:27 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 00:14:27 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 00:14:27 stefans-speaker volumio[4858]: info: Streaming services startup Sep 01 00:14:27 stefans-speaker volumio[4858]: info: Starting Streaming Daemon Sep 01 00:14:27 stefans-speaker volumio[4858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 00:14:27 stefans-speaker sudo[5197]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:27 stefans-speaker sudo[5197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:14:27 stefans-speaker sudo[5197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:27 stefans-speaker sudo[5197]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:27 stefans-speaker volumio[4858]: info: Initializing connection to go-librespot Websocket Sep 01 00:14:27 stefans-speaker volumio[4858]: error: Cannot start Volumio Streaming Daemon Sep 01 00:14:27 stefans-speaker volumio[4858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:14:27 stefans-speaker volumio[4858]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:14:27 stefans-speaker volumio[4858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:14:27 stefans-speaker volumio[4858]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:14:27 stefans-speaker volumio[4858]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 00:14:28 stefans-speaker volumio[4858]: info: MyVolumio token set successfully Sep 01 00:14:28 stefans-speaker volumio[4858]: info: MYVOLUMIO: Adding device Sep 01 00:14:28 stefans-speaker volumio[4858]: info: MYVOLUMIO: Evaluating Server Sep 01 00:14:28 stefans-speaker volumio[4858]: info: MyVolumio status changed Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Streaming services startup Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Starting Streaming Daemon Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Removing audio output: Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Stoppping Tunnel 1 Sep 01 00:14:28 stefans-speaker sudo[5225]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:28 stefans-speaker sudo[5225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:14:28 stefans-speaker sudo[5225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:28 stefans-speaker sudo[5227]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:28 stefans-speaker sudo[5227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 00:14:28 stefans-speaker sudo[5227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:28 stefans-speaker sudo[5225]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:28 stefans-speaker volumio[4858]: error: Cannot start Volumio Streaming Daemon Sep 01 00:14:28 stefans-speaker volumio[4858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:14:28 stefans-speaker volumio[4858]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:14:28 stefans-speaker volumio[4858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 00:14:28 stefans-speaker sudo[5227]: pam_unix(sudo:session): session closed for user root Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Remote SSH Stopped Sep 01 00:14:28 stefans-speaker volumio[4858]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 00:14:28 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:28 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:28 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 00:14:29 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:29 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:29 stefans-speaker go-librespot[5230]: go-librespot daemon starting... Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=debug msg="app state loaded" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:29 stefans-speaker volumio[4858]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=info msg="zeroconf server listening on port 40913" Sep 01 00:14:29 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:29+02:00" level=debug msg="obtained new client token: AAEjkqr3AEod4sU65NcttxrJz6IHaTgbjvmJhrvQixVual5Z8hhVo7ETebskYDGfavUGjf0Qhdj54zD9VMhFWHqEupn/g1QNJ3usNltsmpagjOPFN+se2muboo2FBDQZmcKVj43kIhOAoII13DChO7jM2pPvlLkV6QcKy/kh9nhT5bgAXCybUFb4DXIrh8AGFjXjZOhf3ljm9MiGhLOKKOWE5Y9AjAiJ7UhUpU06KYg1Owk0fBTl84CDmE0=" Sep 01 00:14:29 stefans-speaker volumio[4858]: info: Updating MyVolumio device info Sep 01 00:14:29 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:29 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:29 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:30 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:30 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:30+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:30 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:30+02:00" level=debug msg="completed challenge" Sep 01 00:14:30 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:30+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:30 stefans-speaker volumio[4858]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 00:14:30 stefans-speaker go-librespot[5231]: time="2026-09-01T00:14:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:30 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:30 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:30 stefans-speaker volumio[4858]: info: Initializing connection to go-librespot Websocket Sep 01 00:14:30 stefans-speaker volumio[4858]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:14:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 00:14:33 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:33 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:33 stefans-speaker go-librespot[5238]: go-librespot daemon starting... Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="app state loaded" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:14:33 stefans-speaker volumio[4858]: info: Initializing connection to go-librespot Websocket Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="new websocket client" Sep 01 00:14:33 stefans-speaker volumio[4858]: info: Connection to go-librespot Websocket established Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 00:14:33 stefans-speaker volumio[4858]: info: MYVOLUMIO: Adding device Sep 01 00:14:33 stefans-speaker volumio[4858]: info: MYVOLUMIO: Evaluating Server Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=info msg="zeroconf server listening on port 44397" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="obtained new client token: AAHDl/CelzHj+TiSubyeq/1QsfXPwdX/JEIWcegQd4esj2TVcLXgpNmN2JPGWkNotQI3xt7y9WyGy+HlrxuA/FkVa0yym/GKeb8MNJpYhrV/70ypi7efFYmaIvfDDOJvLhhlH/obYbaV3MrDnN3zLYl04p0eHarzOxr4bhvoGbZbJQu6WxhZkoAv//g2wIwtaYg8U4CEuLToJQjH88tgJTzHTrHVTnKZOWCkAU3SyekWpM/n+Gft1eTdWtk=" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="completed keyexchange" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=debug msg="completed challenge" Sep 01 00:14:33 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:33+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:14:34 stefans-speaker go-librespot[5239]: time="2026-09-01T00:14:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:14:34 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:14:34 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:14:34 stefans-speaker volumio[4858]: info: Connection to go-librespot Websocket closed Sep 01 00:14:34 stefans-speaker volumio[4858]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 00:14:34 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:34 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:34 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:34 stefans-speaker volumio[4858]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:14:35 stefans-speaker volumio[4858]: info: Updating MyVolumio device info Sep 01 00:14:35 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:35 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:35 stefans-speaker volumio[4858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:14:35 stefans-speaker volumio[4858]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 00:14:36 stefans-speaker volumio[4858]: info: Getting Spotify volume Sep 01 00:14:36 stefans-speaker volumio[4858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:14:36 stefans-speaker volumio[4858]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:14:36 stefans-speaker volumio[4858]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 00:14:36 stefans-speaker volumio[4858]: errno: -111, Sep 01 00:14:36 stefans-speaker volumio[4858]: code: 'ECONNREFUSED', Sep 01 00:14:36 stefans-speaker volumio[4858]: syscall: 'connect', Sep 01 00:14:36 stefans-speaker volumio[4858]: address: '127.0.0.1', Sep 01 00:14:36 stefans-speaker volumio[4858]: port: 9879, Sep 01 00:14:36 stefans-speaker volumio[4858]: response: undefined Sep 01 00:14:36 stefans-speaker volumio[4858]: } Sep 01 00:14:36 stefans-speaker volumio[4858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:14:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 00:14:37 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:37 stefans-speaker sudo[5293]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:14:37 stefans-speaker sudo[5293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:13' Sep 01 00:14:37 stefans-speaker sudo[5293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:14:37 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:14:37 stefans-speaker go-librespot[5294]: go-librespot daemon starting... Sep 01 00:14:37 stefans-speaker go-librespot[5296]: time="2026-09-01T00:14:37+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:14:37 stefans-speaker go-librespot[5296]: time="2026-09-01T00:14:37+02:00" level=debug msg="app state loaded" Sep 01 00:14:37 stefans-speaker go-librespot[5296]: time="2026-09-01T00:14:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"