Feb 21 08:58:00 volumio go-librespot[16364]: time="2026-02-21T08:58:00-06:00" level=debug msg="obtained new client token: AAAF1EGHrhFhieI9/kdiYLfy/2JXzKlsfGVvj04cgH9PVWd3Vs5l2hTOS4bkTFv5UwjZJToDchuwTmRV0rlJmQ/E4awZ6I+0NlWCAhdjM+BpXsSRSsXy9A/Ratz41mmfA8FUiPiHgH4a6dgxGzucV4c7ZDOnf5iJvys2O2HW6qB4YKFNmsPch0MLcwyfYEVyHy3nbhwgAaCnpc4jQdRUSmG8AtjpdL5J5oLhrE39WGTn25gw4YH8cMQ="
Feb 21 08:58:00 volumio volumio[16198]: info: Plugin peppymeterbasic is not enabled
Feb 21 08:58:00 volumio volumio[16198]: info: Loading plugin "peppyspectrum"...
Feb 21 08:58:00 volumio go-librespot[16364]: time="2026-02-21T08:58:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:00 volumio go-librespot[16364]: time="2026-02-21T08:58:00-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:00 volumio go-librespot[16364]: time="2026-02-21T08:58:00-06:00" level=debug msg="completed challenge"
Feb 21 08:58:00 volumio go-librespot[16364]: time="2026-02-21T08:58:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:00 volumio volumio-remote-updater[26837]: [2026-02-21 08:58:00] [connect] Successful connection
Feb 21 08:58:00 volumio volumio[16198]: info: Loading i18n strings for locale en
Feb 21 08:58:00 volumio volumio[16198]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored.
Feb 21 08:58:00 volumio volumio[16198]: Updating browse sources language
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::initPlayerControls
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 21 08:58:00 volumio volumio[16198]: Express server listening on port 3000
Feb 21 08:58:00 volumio volumio[16198]: [Metrics] WebUI: 16s 251.27ms
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::resetVolumioState
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::getcurrentVolume
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::volumioRetrievevolume
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:00 volumio sudo[16394]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 21 08:58:00 volumio sudo[16394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:00 volumio sudo[16394]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:00 volumio sudo[16396]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 21 08:58:00 volumio sudo[16396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:00 volumio sudo[16396]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:00 volumio volumio[16198]: info: Volumio Network Manager: Network status updated: 1
Feb 21 08:58:00 volumio volumio[16198]: info: VolumeController:: Volume=100 Mute =false
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::pushState
Feb 21 08:58:00 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::volumioPushState
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::updateTrackBlock
Feb 21 08:58:00 volumio volumio[16198]: info: CorePlayQueue::getTrackBlock
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::volumioRetrievevolume
Feb 21 08:58:00 volumio volumio-remote-updater[26837]: [2026-02-21 08:58:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771685880 101
Feb 21 08:58:00 volumio volumio[16198]: 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: 1
Feb 21 08:58:00 volumio volumio[16198]: info: Reloading queue from file
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::setRepeat null single undefined
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::pushState
Feb 21 08:58:00 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::volumioPushState
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::setRandom null
Feb 21 08:58:00 volumio volumio[16198]: info: CoreStateMachine::pushState
Feb 21 08:58:00 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:00 volumio volumio[16198]: info: CoreCommandRouter::volumioPushState
Feb 21 08:58:00 volumio volumio[16198]: info: Setting Device type: Raspberry PI
Feb 21 08:58:01 volumio volumio[16198]: info: VolumeController:: Volume=100 Mute =false
Feb 21 08:58:01 volumio volumio[16198]: info: CoreStateMachine::pushState
Feb 21 08:58:01 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioPushState
Feb 21 08:58:01 volumio volumio[16198]: info: Completed loading Core Plugins
Feb 21 08:58:01 volumio volumio[16198]: info: Preparing to generate the ALSA configuration file
Feb 21 08:58:01 volumio sudo[16410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 21 08:58:01 volumio sudo[16410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:01 volumio volumio[16198]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954
Feb 21 08:58:01 volumio volumio[16198]: info: Discovery: Found device Volumio
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioGetState
Feb 21 08:58:01 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:01 volumio volumio[16198]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954
Feb 21 08:58:01 volumio volumio[16198]: info: Discovery: Found device Volumio
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioGetState
Feb 21 08:58:01 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:01 volumio volumio[16198]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Feb 21 08:58:01 volumio volumio[16198]: info: Reading ALSA contributions from plugins.
Feb 21 08:58:01 volumio sudo[16410]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:01 volumio volumio[16198]: info: Upmpdcli Daemon Started
Feb 21 08:58:01 volumio volumio[16198]: info: Asound.conf file unchanged, so no further update is needed
Feb 21 08:58:01 volumio volumio[16198]: info: Output device has changed, restarting MPD
Feb 21 08:58:01 volumio volumio[16198]: info: Output device has changed, restarting Shairport Sync
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:01 volumio sudo[16413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 21 08:58:01 volumio sudo[16413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:01 volumio sudo[16413]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:01 volumio sudo[16415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 21 08:58:01 volumio sudo[16415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:01 volumio volumio[16198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 21 08:58:01 volumio volumio[16198]: info: ___________ START PLUGINS ___________
Feb 21 08:58:01 volumio volumio[16198]: info: ControllerMpd::onStart: Initializing MPD
Feb 21 08:58:01 volumio volumio[16198]: info: Creating MPD Configuration file
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881213] CoreMusicLibrary::Adding element Media Servers
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 21 08:58:01 volumio sudo[16423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 21 08:58:01 volumio sudo[16423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:01 volumio sudo[16423]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:01 volumio sudo[16425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 21 08:58:01 volumio sudo[16425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:01 volumio volumio[16198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881332] CoreMusicLibrary::Adding element Last_100
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 21 08:58:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 21 08:58:01 volumio systemd[1]: mpd.service: Consumed 4.108s CPU time.
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881339] CoreMusicLibrary::Adding element Webradio
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 21 08:58:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 21 08:58:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 21 08:58:01 volumio volumio[16198]: info: Initializing BBC Radios
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881412] CoreMusicLibrary::Adding element Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881423] CoreMusicLibrary::Adding element SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 21 08:58:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 21 08:58:01 volumio volumio[16198]: info: Creating Spotify config file
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:01 volumio sudo[16450]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 21 08:58:01 volumio sudo[16450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 21 08:58:01 volumio sudo[16450]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:01 volumio volumio[16198]: info: [squeezelite_mc] Starting proxy server...
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881784] CoreMusicLibrary::Adding element YouTube2
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881803] CoreMusicLibrary::Adding element YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881814] CoreMusicLibrary::Adding element Podcast
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Podcast
Feb 21 08:58:01 volumio volumio[16198]: info: Loading i18n strings for locale en
Feb 21 08:58:01 volumio volumio[16198]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored.
Feb 21 08:58:01 volumio volumio[16198]: Updating browse sources language
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Podcast
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Podcast
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:01 volumio volumio[16198]: info: [1771685881863] CoreMusicLibrary::Adding element Volusonic
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Podcast
Feb 21 08:58:01 volumio volumio[16198]: Cannot find translation for source Volusonic
Feb 21 08:58:01 volumio volumio[16198]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago
Feb 21 08:58:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Feb 21 08:58:01 volumio volumio[16198]: info: Volumio Calling Home
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Proxy server started on port 38273
Feb 21 08:58:02 volumio volumio[16198]: info: Preparing to generate the ALSA configuration file
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::volumioRetrievevolume
Feb 21 08:58:02 volumio volumio[16198]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Feb 21 08:58:02 volumio volumio[16198]: info: Reading ALSA contributions from plugins.
Feb 21 08:58:02 volumio volumio[16198]: info: MPD Permissions set
Feb 21 08:58:02 volumio volumio[16198]: info: MPD Permissions set
Feb 21 08:58:02 volumio volumio[16198]: info: Spotify config file written
Feb 21 08:58:02 volumio sudo[16460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Feb 21 08:58:02 volumio sudo[16460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:02 volumio volumio[16198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Feb 21 08:58:02 volumio volumio[16198]: info: VolumeController:: Volume=100 Mute =false
Feb 21 08:58:02 volumio volumio[16198]: info: CoreStateMachine::pushState
Feb 21 08:58:02 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::volumioPushState
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Server discovery started
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Player finder started
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true
Feb 21 08:58:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio go-librespot[16464]: go-librespot daemon starting...
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:02 volumio sudo[16460]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="app state loaded"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:02 volumio volumio[16198]: info: No need to fix Spotify hosts
Feb 21 08:58:02 volumio volumio[16198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 21 08:58:02 volumio volumio[16198]: info: Volumio called home
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=info msg="zeroconf server listening on port 46259"
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"]
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"}
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf
Feb 21 08:58:02 volumio volumio[16198]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Feb 21 08:58:02 volumio volumio[16198]: SPOTIFY: BQBtDHTiDdsjXyWeM3l4iyGP_9nruQNMRm7Jw5NJVY82Pvh7wM2jBtOM4urSTu4EW7hFepCkRXnjIu7HFN91OuqGkjSygLeAd-3sfHE_8x_LU69kwoPOji4rfQQe75twL9hvEjBlINEJb9k5hIsTB7GjKWqW5j0fJUlALl5ONhrq7WZOJLWrq5RPBvyosUNPek3DW6-5ZyR01ipc2NWVeZJJ3yUQS17RwAYhRnY
Feb 21 08:58:02 volumio volumio[16198]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Feb 21 08:58:02 volumio volumio[16198]: info: New Spotify access token = BQBtDHTiDdsjXyWeM3l4iyGP_9nruQNMRm7Jw5NJVY82Pvh7wM2jBtOM4urSTu4EW7hFepCkRXnjIu7HFN91OuqGkjSygLeAd-3sfHE_8x_LU69kwoPOji4rfQQe75twL9hvEjBlINEJb9k5hIsTB7GjKWqW5j0fJUlALl5ONhrq7WZOJLWrq5RPBvyosUNPek3DW6-5ZyR01ipc2NWVeZJJ3yUQS17RwAYhRnY
Feb 21 08:58:02 volumio volumio[16198]: info: Spotify credentials grant success - running version from March 24, 2019
Feb 21 08:58:02 volumio sudo[16484]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf
Feb 21 08:58:02 volumio sudo[16484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:02 volumio volumio[16198]: info: Starting Shairport Sync
Feb 21 08:58:02 volumio sudo[16484]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="obtained new client token: AACGZZ+d5PE3hdJrYgfhdMlR8wVC0Ym7eHjafMbfaioFdUpJY0UzmdmRSYtHcsk17IEXgtJZp8/KvZ02VunpsLIm5xiD63jdWuvd8x+kqtSHpcciElmu7gAAIU0KjuVxrV60+Tc/Mae+IkywhBJyVdwXfZjssMW7bp2KYKVKywz4Krv30hmbRtrQ4uotuOq4v2xPD6IyHBhDLWqVeOYme8PxiniLyRAZy17+aBL/sJaOLYfhS3gUdQzbYg=="
Feb 21 08:58:02 volumio volumio[16198]: info: Starting Shairport Sync
Feb 21 08:58:02 volumio volumio[16198]: info: Starting Shairport Sync
Feb 21 08:58:02 volumio sudo[16488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 21 08:58:02 volumio sudo[16488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:02 volumio sudo[16490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 21 08:58:02 volumio sudo[16490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:02 volumio sudo[16494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=debug msg="completed challenge"
Feb 21 08:58:02 volumio sudo[16494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 21 08:58:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:02 volumio systemd[1]: shairport-sync.service: Consumed 2.142s CPU time.
Feb 21 08:58:02 volumio go-librespot[16470]: time="2026-02-21T08:58:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:02 volumio volumio[16198]: info: [squeezelite_mc] Executing alsactl -L -R nrestore
Feb 21 08:58:02 volumio volumio[16198]: info: Asound.conf file unchanged, so no further update is needed
Feb 21 08:58:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:02 volumio volumio[16198]: info: Output device has changed, restarting MPD
Feb 21 08:58:02 volumio sudo[16490]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:03 volumio sudo[16488]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 21 08:58:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 21 08:58:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:03 volumio sudo[16499]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore
Feb 21 08:58:03 volumio sudo[16499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:03 volumio sudo[16503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 21 08:58:03 volumio sudo[16503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:03 volumio volumio[16198]: info: Output device has changed, restarting Shairport Sync
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:03 volumio sudo[16499]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio sudo[16494]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio sudo[16501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 21 08:58:03 volumio sudo[16501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:03 volumio sudo[16501]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio volumio[16198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 21 08:58:03 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 21 08:58:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 21 08:58:03 volumio systemd[1]: mpd.service: Consumed 1.578s CPU time.
Feb 21 08:58:03 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 21 08:58:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 21 08:58:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 21 08:58:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 21 08:58:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 21 08:58:03 volumio volumio[16198]: info: MPD Permissions set
Feb 21 08:58:03 volumio volumio[16198]: info: Shairport-Sync Started
Feb 21 08:58:03 volumio volumio[16198]: Error adding Membership: Error: addMembership EINVAL
Feb 21 08:58:03 volumio volumio[16198]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
Feb 21 08:58:03 volumio volumio[16198]: info: Shairport-Sync Started
Feb 21 08:58:03 volumio volumio[16198]: info: Shairport-Sync Started
Feb 21 08:58:03 volumio sudo[16534]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
Feb 21 08:58:03 volumio sudo[16534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:03 volumio sudo[16534]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio sudo[16537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Feb 21 08:58:03 volumio sudo[16537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:03 volumio systemd[1]: Reloading.
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::volumioGetState
Feb 21 08:58:03 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:03 volumio volumio[16198]: info: Starting Shairport Sync
Feb 21 08:58:03 volumio sudo[16531]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 21 08:58:03 volumio sudo[16531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 21 08:58:03 volumio sudo[16531]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:03 volumio volumio[16198]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"}
Feb 21 08:58:03 volumio volumio[16198]: info: Spotify Successfully logged in
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 21 08:58:03 volumio volumio[16198]: info: [1771685883646] CoreMusicLibrary::Adding element Spotify
Feb 21 08:58:03 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source Bandcamp Discover
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source SoundCloud
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source YouTube2
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source YouTube Music
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source Podcast
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source Volusonic
Feb 21 08:58:03 volumio volumio[16198]: Cannot find translation for source Spotify
Feb 21 08:58:03 volumio sudo[16546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 21 08:58:03 volumio sudo[16546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:04 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:04 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:04 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:04 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 21 08:58:05 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 21 08:58:05 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 21 08:58:05 volumio sudo[16537]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:05 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:05 volumio sudo[16567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:05 volumio sudo[16567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:05 volumio sudo[16567]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 21 08:58:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 21 08:58:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:05 volumio systemd[1]: shairport-sync.service: Consumed 1.521s CPU time.
Feb 21 08:58:05 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true
Feb 21 08:58:05 volumio sudo[16571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite
Feb 21 08:58:05 volumio sudo[16571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 21 08:58:05 volumio sudo[16546]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:05 volumio volumio[16198]: info: Shairport-Sync Started
Feb 21 08:58:05 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator...
Feb 21 08:58:05 volumio volumio[16198]: info: go-librespot daemon successfully initialized
Feb 21 08:58:05 volumio systemd[1]: squeezelite.service: Deactivated successfully.
Feb 21 08:58:05 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator.
Feb 21 08:58:05 volumio sudo[16571]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111.
Feb 21 08:58:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:06 volumio go-librespot[16587]: go-librespot daemon starting...
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="app state loaded"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:06 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:06 volumio sudo[16595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:06 volumio sudo[16595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:06 volumio sudo[16595]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:06 volumio volumio[16198]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log
Feb 21 08:58:06 volumio sudo[16600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log
Feb 21 08:58:06 volumio sudo[16600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:06 volumio sudo[16600]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:06 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=info msg="zeroconf server listening on port 40185"
Feb 21 08:58:06 volumio sudo[16603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite
Feb 21 08:58:06 volumio sudo[16603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="obtained new client token: AADNZApdHSAWYpm1E9qOHSOI+pxa/0Rr1D52zHDJi2O+J0871+v2QNprBDv05C48e6jMeAhB4+wSH9g8zKpFCW65coasg0qg4PUQtjEfvTaH1HQmBoQwejOguB894a0YmFOzyhFbqlcwf3ROsYFQ1DSVtmp6MnjNO+8EpHtMxPKzs7+zacr1sBVx98t0cQVRKDsUBevYuU8HrkrylNSh8SCI25CflZq6rJQH7+IU8UIWaR83Wd1GWLBrag=="
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:06 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator...
Feb 21 08:58:06 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator.
Feb 21 08:58:06 volumio sudo[16603]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=debug msg="completed challenge"
Feb 21 08:58:06 volumio go-librespot[16588]: time="2026-02-21T08:58:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:07 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:07 volumio sudo[16624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:07 volumio sudo[16624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:07 volumio sudo[16624]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:07 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:07 volumio sudo[16627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:07 volumio sudo[16627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:08 volumio sudo[16627]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:08 volumio mpd[16565]: 2026-02-21T08:58:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 21 08:58:08 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:08 volumio sudo[16630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:08 volumio sudo[16630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:08 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 21 08:58:08 volumio sudo[16415]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:08 volumio sudo[16425]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:08 volumio sudo[16503]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:08 volumio sudo[16630]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:08 volumio volumio[16198]: error: MPD error: The expression evaluated to a falsy value:
Feb 21 08:58:08 volumio volumio[16198]: assert.ok(self.idling)
Feb 21 08:58:08 volumio volumio[16198]: error: The expression evaluated to a falsy value:
Feb 21 08:58:08 volumio volumio[16198]: assert.ok(self.idling)
Feb 21 08:58:08 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:08 volumio volumio[16198]: error: MPD error: The expression evaluated to a falsy value:
Feb 21 08:58:08 volumio volumio[16198]: assert.ok(self.idling)
Feb 21 08:58:08 volumio volumio[16198]: error: The expression evaluated to a falsy value:
Feb 21 08:58:08 volumio volumio[16198]: assert.ok(self.idling)
Feb 21 08:58:08 volumio volumio[16198]: info: MPD running with PID16565
Feb 21 08:58:08 volumio volumio[16198]: ,establishing connection
Feb 21 08:58:08 volumio volumio[16198]: error: updateQueue error: null
Feb 21 08:58:08 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:08 volumio volumio[16198]: error: updateQueue error: null
Feb 21 08:58:09 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:09 volumio sudo[16637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:09 volumio sudo[16637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:09 volumio sudo[16637]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:09 volumio volumio[16198]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Feb 21 08:58:09 volumio sudo[16640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Feb 21 08:58:09 volumio sudo[16640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112.
Feb 21 08:58:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:09 volumio go-librespot[16642]: go-librespot daemon starting...
Feb 21 08:58:09 volumio sudo[16640]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:09 volumio volumio[16198]: info: Completed starting Core Plugins
Feb 21 08:58:09 volumio volumio[16198]: info: -------------------------------------------
Feb 21 08:58:09 volumio volumio[16198]: info: ----- MyVolumio plugins startup ----
Feb 21 08:58:09 volumio volumio[16198]: info: -------------------------------------------
Feb 21 08:58:09 volumio go-librespot[16643]: time="2026-02-21T08:58:09-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:09 volumio go-librespot[16643]: time="2026-02-21T08:58:09-06:00" level=debug msg="app state loaded"
Feb 21 08:58:09 volumio go-librespot[16643]: time="2026-02-21T08:58:09-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:09 volumio volumio[16198]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 21 08:58:09 volumio go-librespot[16643]: time="2026-02-21T08:58:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=info msg="zeroconf server listening on port 42151"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="obtained new client token: AABTp/cWfY4ljCKQnieCO+qZegcix+E/J36FWqYWa+0183Sa/jw38R6+MHMB8LOWniau13wEAGfIExQLRV7EB3OjDaHDOKF2r3oGsfVEEElHfNlBvA18j1eWUcPtDvB4DjILJgktKdZapWIIeQiKiGy0bG66+lrMc014r90gz3BHbbFMKfXFS23KRlnOhgio8goO59iR82X5b6BTP7EhJX4tLk8PobaKo22d/0wlsykcOlIxRRd63aG6+A=="
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=debug msg="completed challenge"
Feb 21 08:58:10 volumio go-librespot[16643]: time="2026-02-21T08:58:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:11 volumio sudo[16652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 21 08:58:11 volumio sudo[16652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:11 volumio sudo[16654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 21 08:58:11 volumio sudo[16654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:11 volumio sudo[16656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 21 08:58:11 volumio sudo[16656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Feb 21 08:58:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Feb 21 08:58:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Feb 21 08:58:11 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:11 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Feb 21 08:58:11 volumio sudo[16656]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:11 volumio sudo[16652]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:11 volumio sudo[16654]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:11 volumio mpd_monitor.sh[16660]: MPD Monitor Service: Starting MPD Monitor Service
Feb 21 08:58:11 volumio volumio[16198]: info: Successfully started MPD Monitor
Feb 21 08:58:11 volumio volumio[16198]: info: Successfully started MPD Monitor
Feb 21 08:58:11 volumio volumio[16198]: info: Successfully started MPD Monitor
Feb 21 08:58:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113.
Feb 21 08:58:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:13 volumio go-librespot[16664]: go-librespot daemon starting...
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=debug msg="app state loaded"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:13 volumio go-librespot[16665]: time="2026-02-21T08:58:13-06:00" level=info msg="zeroconf server listening on port 38075"
Feb 21 08:58:14 volumio go-librespot[16665]: time="2026-02-21T08:58:14-06:00" level=debug msg="obtained new client token: AACvujMVP6TJ/GiKxb72IFZz+ZR3KtIbhwDSTSg4SLUWTZzWq9Dbb9t110aKulA5k+Ak8TjE8E6MLRgQjxhyZPvYqmFa3fcCIh4XpQ3QZR3HF4Nkx3Dp273PzU3TMJFMGQGPWwcpyh6yr+6m19d1Yb56HFKhxEvbSPDPibkxcIUrtPGdZ4k81B5O0+NynjJmn5h8PBT0WwkvzNO34R0fYI6DI2JC62WZs0eti5jLeZgbR3cCIfQIvrQ="
Feb 21 08:58:14 volumio go-librespot[16665]: time="2026-02-21T08:58:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:14 volumio go-librespot[16665]: time="2026-02-21T08:58:14-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:14 volumio go-librespot[16665]: time="2026-02-21T08:58:14-06:00" level=debug msg="completed challenge"
Feb 21 08:58:14 volumio go-librespot[16665]: time="2026-02-21T08:58:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:14 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:15 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114.
Feb 21 08:58:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:17 volumio go-librespot[16686]: go-librespot daemon starting...
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="app state loaded"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=info msg="zeroconf server listening on port 37419"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="obtained new client token: AABqazNd5UHXHiFwLwUuiUuMelXJxqVTyKLeqIi6PCas2vWsBlalYaF923Iexw7hIx0lN/2GtPDzMxwrrvBkuwiv9waYp6U4uxf6E/BO0eFLW80Ops9TsakVoQTM+d5eaGXMmZEycYu0hJmrVmPVq3xkDbPMFSoS0pM9dmab8tyv4/1BxiDI9oHy8HuxdBGf+FrOL9QH182h0FnTaAcPrxPtQ22SzkIK2zWXT4h9tPtYH3dgCwHOpRgLRQ=="
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=debug msg="completed challenge"
Feb 21 08:58:17 volumio go-librespot[16687]: time="2026-02-21T08:58:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:18 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:18 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin multiroom to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 21 08:58:18 volumio volumio[16198]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 21 08:58:19 volumio volumio[16198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 21 08:58:19 volumio volumio[16198]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 21 08:58:19 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:19 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:19 volumio volumio[16198]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 21 08:58:19 volumio volumio[16198]: info: MyVolumio login type: Token
Feb 21 08:58:19 volumio volumio[16198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 21 08:58:19 volumio volumio[16198]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 21 08:58:20 volumio volumio[16198]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 21 08:58:20 volumio volumio[16198]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 21 08:58:20 volumio volumio[16198]: info: Streaming services startup
Feb 21 08:58:20 volumio volumio[16198]: info: Starting Streaming Daemon
Feb 21 08:58:20 volumio sudo[16695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 21 08:58:20 volumio sudo[16695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 21 08:58:20 volumio volumio[16198]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 21 08:58:20 volumio sudo[16695]: pam_unix(sudo:session): session closed for user root
Feb 21 08:58:20 volumio volumio[16198]: error: Cannot start Volumio Streaming Daemon
Feb 21 08:58:20 volumio volumio[16198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 21 08:58:20 volumio volumio[16198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 21 08:58:20 volumio volumio[16198]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required.
Feb 21 08:58:21 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:21 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115.
Feb 21 08:58:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:21 volumio go-librespot[16701]: go-librespot daemon starting...
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="app state loaded"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=info msg="zeroconf server listening on port 39375"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="obtained new client token: AACgmUyiTHVcmNfhz5GE0UGyKZBaXX7yFGk33XY9MONpz3XMylJ/Fh+LE14en7nAFPbq0JhAZkF8ngKMJU8FOZIiP1pcNrGXGMfSPOFt939LVozPrKy9+xGmvX0vK6eVu9wLNV0GBh0tr/RBhSK38aThpGVDa6x1dlHrfB6HH/lFrgtMS0bljN4lJ/COSgvezJn6oIoNna8klM7w+3eAJJQWiecI5km0lmEH1rhqS0WNIhxGknMpTHrM8w=="
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=debug msg="completed challenge"
Feb 21 08:58:21 volumio go-librespot[16702]: time="2026-02-21T08:58:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:24 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:24 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116.
Feb 21 08:58:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:24 volumio go-librespot[16709]: go-librespot daemon starting...
Feb 21 08:58:24 volumio go-librespot[16710]: time="2026-02-21T08:58:24-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:24 volumio go-librespot[16710]: time="2026-02-21T08:58:24-06:00" level=debug msg="app state loaded"
Feb 21 08:58:24 volumio go-librespot[16710]: time="2026-02-21T08:58:24-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:24 volumio go-librespot[16710]: time="2026-02-21T08:58:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=info msg="zeroconf server listening on port 39657"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="obtained new client token: AABpOdElsYW9RalLMbXtxjonI5T/TkGA5DlRU+7htR87aV3dZxGmpW4Smv1c6QT3o08jvGQvPwT1iDJM/0woFWrfSGt37ABd/Ii12hhVBAKbD9LfmxhyZowM6eeWQjL5zRu3YBrljCT6TWwt76EIn39gV13/PLW+YsvFyjWi9mx/qGKwAHQEqTy/VYPAb37jLycPezySQvhMuT+3JFMF1ciE5eYxaLq8Ft80WyPDUhTuzPevbavK+2mDxg=="
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=debug msg="completed challenge"
Feb 21 08:58:25 volumio go-librespot[16710]: time="2026-02-21T08:58:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:27 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:27 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117.
Feb 21 08:58:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:28 volumio go-librespot[16731]: go-librespot daemon starting...
Feb 21 08:58:28 volumio go-librespot[16732]: time="2026-02-21T08:58:28-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:28 volumio go-librespot[16732]: time="2026-02-21T08:58:28-06:00" level=debug msg="app state loaded"
Feb 21 08:58:28 volumio go-librespot[16732]: time="2026-02-21T08:58:28-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:28 volumio go-librespot[16732]: time="2026-02-21T08:58:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=info msg="zeroconf server listening on port 45173"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="obtained new client token: AABCwJjAcDcUy+IX64iiHcM06ovJI+jRBWzBN9uhYY0w1I6BUKWaUVvDifWPNkbUU/ZJBypNvh4oHj1EowR0IXb1f41A8AqX6UONjcEubQUSfD1JbMS7j4vLNJ32x6Wy1T22O8Gpjwjdj1d6RE5M/VJs1qtT3RNKuf+aJn5hZf8Zyn5/JE5Ir4vBx9W1FnJIk16kpUrSGwiWvrPsX1kWYAo45ikxIOt975eMAWsaufZlWJe7eP/zLnc="
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=debug msg="completed challenge"
Feb 21 08:58:29 volumio go-librespot[16732]: time="2026-02-21T08:58:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:30 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:30 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:31 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 21 08:58:31 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:31 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 21 08:58:31 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 21 08:58:31 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 21 08:58:31 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 21 08:58:31 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 21 08:58:32 volumio volumio[16198]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for CPU utilization spike
\n- Fix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\n- Add BLE onboarding plugin
\n- Enable vc4-kms-v3d for all compatible RPi models
\n- Update Tidal SDK
\n
\n","title":"Update v4.096","updateavailable":true}
Feb 21 08:58:32 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 21 08:58:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118.
Feb 21 08:58:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:32 volumio go-librespot[16747]: go-librespot daemon starting...
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="app state loaded"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=info msg="zeroconf server listening on port 45779"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="obtained new client token: AACM9hYV11QZE10/xO1wZx0FFld5ycKTy1nFJaVE2QBba1LnqVxN/ptC/2IGNjlStH/gyivyuKCm0Cg3kpNhGhb1EaN2kZL8179a0vLPchzP2KKppgOE5+7H2oZaE1g9WdRGBWUc0/a370xUIqKTkO8cszLKl9rweSzUv25hPrrv0LqMdRSBPWpSnw/uAnsbzX9ciU/r/uDfnHOwU6AwBfhe4ntw8SghRJXV8FTAz2RjbhzVh2x7bPNaAQ=="
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=debug msg="completed challenge"
Feb 21 08:58:32 volumio go-librespot[16748]: time="2026-02-21T08:58:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:33 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:33 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:34 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 21 08:58:34 volumio volumio[16198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 21 08:58:34 volumio volumio[16198]: info: CoreCommandRouter::volumioGetState
Feb 21 08:58:34 volumio volumio[16198]: info: CorePlayQueue::getTrack 0
Feb 21 08:58:36 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:36 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119.
Feb 21 08:58:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:36 volumio go-librespot[16755]: go-librespot daemon starting...
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="app state loaded"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=info msg="zeroconf server listening on port 39417"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="obtained new client token: AADWHhFWDstnO47GNJmexSiKaxsr+pr80VJRQ+qJNn8Xhg5BnvS2wUXTUFugvaLa+LvIsaxmWTimSf9EMDXPC1mxzD5V3aSbl6JkCVMns6+q9Slq3/OV8ZrZYh1ykcIUulAIExFcagRebd0hUdwFNdiUy+Fpb1tzHyo4jE+0Pk94IwNFR+ucmFJ1F1QmhY81+E8GvzHGpLd7BXbKLEWS0viEIFpkyypbTrJLlLE2NRWlmQHTL5YhVV7BUw=="
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=debug msg="completed challenge"
Feb 21 08:58:36 volumio go-librespot[16756]: time="2026-02-21T08:58:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:39 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:39 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 21 08:58:39 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 21 08:58:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120.
Feb 21 08:58:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:39 volumio go-librespot[16777]: go-librespot daemon starting...
Feb 21 08:58:39 volumio go-librespot[16778]: time="2026-02-21T08:58:39-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:39 volumio go-librespot[16778]: time="2026-02-21T08:58:39-06:00" level=debug msg="app state loaded"
Feb 21 08:58:39 volumio go-librespot[16778]: time="2026-02-21T08:58:39-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:39 volumio go-librespot[16778]: time="2026-02-21T08:58:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:40 volumio volumio[16198]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 21 08:58:40 volumio volumio[16198]: info: Completed starting MyVolumio Plugin
Feb 21 08:58:40 volumio volumio[16198]: [Metrics] CommandRouter: 55s 201.71ms
Feb 21 08:58:40 volumio volumio[16198]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 21 08:58:40 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 21 08:58:40 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 21 08:58:40 volumio volumio[16198]: info: CoreCommandRouter::Close All Modals sent
Feb 21 08:58:40 volumio volumio[16198]: info: CoreCommandRouter::Close All Modals sent
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=info msg="zeroconf server listening on port 37195"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="obtained new client token: AACPHCKhE+3UMQmcxCPmrxRDuYGzRPbCFdq1hDeuPxh5oz2l970WRvsazx0GUxC65EiM/pZRiwUrjN2YzhnlCqGIw9nAUon/dL/9L53WAoQ6LdZc2fX7ffRaUL6qY12DjoUWu1e7p54OGm1fOO85bNLeiPwRp4xwiEgwOW/DgNNrUOu6TBMki7xgEeNJlY3iQXICZvlT2bpbXHPvGPtDbcZHxYNDt/hCLYyNsO0nvsqfy8iPymB+0wJ7AQ=="
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=debug msg="completed challenge"
Feb 21 08:58:40 volumio go-librespot[16778]: time="2026-02-21T08:58:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:41 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 21 08:58:41 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 21 08:58:41 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 21 08:58:42 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:42 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121.
Feb 21 08:58:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:43 volumio go-librespot[16791]: go-librespot daemon starting...
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=debug msg="app state loaded"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:58:43 volumio go-librespot[16792]: time="2026-02-21T08:58:43-06:00" level=info msg="zeroconf server listening on port 39567"
Feb 21 08:58:44 volumio go-librespot[16792]: time="2026-02-21T08:58:44-06:00" level=debug msg="obtained new client token: AACyzeFgxnS4K6g5gKvLf4+Sv9PdRvIMKNHUTxPfQUJTETDKiFT9UFxqDUsS1AJXf4stSIOFVl4esi1qn7t198BZNmaYjDTeJd/oygQ2tmWBnRhloFJQcW69DP4GoxcYBwTms127t6vTlwEIIk/YIkj208kpiIj0bFe+2aqm3IhwTFeh+5URXiaz/9aBYdp4w5qIjsqm/msGbyiyvAbsfnZK8pBuqKEYh0yS7WgyB0p3RuZdFStdVR8="
Feb 21 08:58:44 volumio go-librespot[16792]: time="2026-02-21T08:58:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:44 volumio go-librespot[16792]: time="2026-02-21T08:58:44-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:44 volumio go-librespot[16792]: time="2026-02-21T08:58:44-06:00" level=debug msg="completed challenge"
Feb 21 08:58:44 volumio go-librespot[16792]: time="2026-02-21T08:58:44-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:45 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:45 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:47 volumio volumio[16198]: info: BOOT COMPLETED
Feb 21 08:58:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122.
Feb 21 08:58:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:47 volumio go-librespot[16813]: go-librespot daemon starting...
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="app state loaded"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=info msg="zeroconf server listening on port 32937"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="obtained new client token: AADJWGiOQUD6mTZJBQUCDnrwuZa1Pq4hm/eDzJCWJMBqwhQX6TeZkiaUXD3M57vRA1j0rGKzddJBttFmW7lf70kPTmDO4MDCTt8hDNRTPcAMU7kqTZ2M8jtXh3T2SEijZVQLAAcQK0jdim/LxrwsQSojz+EYQf/yYrA/Odk1tt8uqa8JY4dMkgQ78psnnmwmQB2GoFPGRJIMiFT7QcnI8sIDelK0epCUxa7/2zPW17gxkrRq8SyiK8z44Q=="
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=debug msg="completed challenge"
Feb 21 08:58:47 volumio go-librespot[16814]: time="2026-02-21T08:58:47-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:48 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:48 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:51 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123.
Feb 21 08:58:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:51 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:51 volumio go-librespot[16822]: go-librespot daemon starting...
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="app state loaded"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=info msg="zeroconf server listening on port 38167"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="obtained new client token: AACr785nkM8marJLrOkZbwdiz5AkF5CU4b+OF70UAOAkE9cDEmK31GZ/lvuzDF5CQxRr/QsJdm3VCqOylZgVNJuyIYxwUfTu9BeMPtOr4CbqasDEZZoF9LOEMohV1cdCvD9cFgm2kGkokdz1yAIaQImdkDwGsMe67HlmhqQD2W9SX2/RApk/pe7PpTBkky0aHDyK3vNCeWKgHqHcbKQxQjg1t7obigeto/pV2NvdX5JzrxxMsi9MGeC+ZA=="
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=debug msg="completed challenge"
Feb 21 08:58:51 volumio go-librespot[16823]: time="2026-02-21T08:58:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:54 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:54 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124.
Feb 21 08:58:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:54 volumio go-librespot[16833]: go-librespot daemon starting...
Feb 21 08:58:54 volumio go-librespot[16834]: time="2026-02-21T08:58:54-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:54 volumio go-librespot[16834]: time="2026-02-21T08:58:54-06:00" level=debug msg="app state loaded"
Feb 21 08:58:54 volumio go-librespot[16834]: time="2026-02-21T08:58:54-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:54 volumio go-librespot[16834]: time="2026-02-21T08:58:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=info msg="zeroconf server listening on port 45093"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="obtained new client token: AADQtm96pjPrMQbRJZSR/nAIRrEnO2kLUqb4Ek4KMkUVKPbHanP0jzUAVOeLyWQgcwnYhn4YzZl13iUCuXepb3yCNTkQNoGd2dHqBseP/pvZ8yJk/tOVqvUan1oMOFqpI28/fvem2hBFVfg6nr1nI23EzZW9gWzrXvM3e9nz6zWqU/5G/5mfj/+QU39S2Fi+0zSninYR8JS2UqIDh7kHRrj871S48vP8i4jhQyKffKajIDDHcDwwfzRKbQ=="
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=debug msg="completed challenge"
Feb 21 08:58:55 volumio go-librespot[16834]: time="2026-02-21T08:58:55-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:58:57 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:58:57 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:58:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125.
Feb 21 08:58:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:58:58 volumio go-librespot[16855]: go-librespot daemon starting...
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=debug msg="app state loaded"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=debug msg="stored credentials not found"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:58:58 volumio go-librespot[16856]: time="2026-02-21T08:58:58-06:00" level=info msg="zeroconf server listening on port 43709"
Feb 21 08:58:59 volumio go-librespot[16856]: time="2026-02-21T08:58:59-06:00" level=debug msg="obtained new client token: AAAIh92H4Uawd7lxt/6OZyn8PzFcVlCb/i/Yx3Y8HbRXRSPGBmKO/MzKan0qkgSitrBj6yo3wUSaL/O3kPUPAWy/f784/VgXBaRO+ChNIRnuFdTW5KbQv7SXU62yaLssKbybXqab8Ui6wPxbqptOYlL0vdizALEJ8ZWlTVEY4Re9acDRcoKQZQFgwZmPCKtCeJf07UirEnEa/vahvfoitRzXpS7OkXaXXeQWHEjmwBm6cEdRKWhAtTI="
Feb 21 08:58:59 volumio go-librespot[16856]: time="2026-02-21T08:58:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:58:59 volumio go-librespot[16856]: time="2026-02-21T08:58:59-06:00" level=debug msg="completed keyexchange"
Feb 21 08:58:59 volumio go-librespot[16856]: time="2026-02-21T08:58:59-06:00" level=debug msg="completed challenge"
Feb 21 08:58:59 volumio go-librespot[16856]: time="2026-02-21T08:58:59-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:58:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:58:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:00 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:00 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:01 volumio volumio[16198]: info: UPDATER: Scheduling automatic update
Feb 21 08:59:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 21 08:59:01 volumio volumio[16198]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 21 08:59:01 volumio volumio[16198]: info: UPDATER: Auto update will take place at: Sun Feb 22 2026 05:40:10 GMT-0600 (Central Standard Time)
Feb 21 08:59:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126.
Feb 21 08:59:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:02 volumio go-librespot[16863]: go-librespot daemon starting...
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="app state loaded"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=info msg="zeroconf server listening on port 36485"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="obtained new client token: AABp0hF1VwiVoqBVkMHN2Q6i+wSxKFpaxrdqjZb/K5yHK2E6xv2BYVBXfNH080Kr1MFcGIE/LkK7jqKCnhEFDT3Mi1gZ5hGzvX1nJUMgqfINusgoUch8zc6qgJiS5X9j1vnYh1tuSmuTHVy/4f6B+/PMbGKAaieV/oLGA7UzgDAJUalY0m23mRD75UbjEA7GQyiIY9NTfmvEdUXn+J8gu6wubrXuskCh33PWaZfJOPKvHlwwFFuHxZRbwA=="
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=debug msg="completed challenge"
Feb 21 08:59:02 volumio go-librespot[16864]: time="2026-02-21T08:59:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:03 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:03 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127.
Feb 21 08:59:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:06 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:06 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:06 volumio go-librespot[16872]: go-librespot daemon starting...
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="app state loaded"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=info msg="zeroconf server listening on port 41605"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="obtained new client token: AABymZGdUHUUDsVV4zDVBYH9jvY/+qbSDjNRJG2WMbCozvffofGxsp5I7hOmlHT3jKeEVc+NEQ7ryNtzayaspvuTyH6YK73L1L0EkrPoKxfA8mIm4/scLLHRqyqAhrVfODgCEF83pkmUw6Qn8HbtygY+mCDuBhaPb2xhvYRkMcr6OKMoICcLJCFEXRY2COPF/60kvkti2Cq0WQtUGoVTdrTZ8QMcAxoV2T5hceWFj30S1OBX7wmCT6QHqQ=="
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=debug msg="completed challenge"
Feb 21 08:59:06 volumio go-librespot[16873]: time="2026-02-21T08:59:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:09 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:09 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128.
Feb 21 08:59:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:09 volumio go-librespot[16894]: go-librespot daemon starting...
Feb 21 08:59:09 volumio go-librespot[16895]: time="2026-02-21T08:59:09-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:09 volumio go-librespot[16895]: time="2026-02-21T08:59:09-06:00" level=debug msg="app state loaded"
Feb 21 08:59:09 volumio go-librespot[16895]: time="2026-02-21T08:59:09-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:09 volumio go-librespot[16895]: time="2026-02-21T08:59:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=info msg="zeroconf server listening on port 42897"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="obtained new client token: AADmu2f80CdQXy/ReONqMbXa5CiAgHvbh7ljUps7oaomcbHTQCjhoonPcQdUgqA+hCtFTZa4KqdmW/LnTCrd0uz8nlDNQvNtkFptcXOsXMGst68kZh0TPc1ZRL/4r9/Le5i34YgLDvmV1VTDdIJViln8BL3oZ8nn1Y8r7CqgrQWsX0xF9Dwe/CjBymGsB7kOVWtI/8WK82QTzpuL5imeYgxVkij3NrV5H4EHTeysBdwnfyrCcianEQ3EBA=="
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=debug msg="completed challenge"
Feb 21 08:59:10 volumio go-librespot[16895]: time="2026-02-21T08:59:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:12 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:12 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129.
Feb 21 08:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:13 volumio go-librespot[16906]: go-librespot daemon starting...
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=debug msg="app state loaded"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:13 volumio go-librespot[16907]: time="2026-02-21T08:59:13-06:00" level=info msg="zeroconf server listening on port 46079"
Feb 21 08:59:14 volumio go-librespot[16907]: time="2026-02-21T08:59:14-06:00" level=debug msg="obtained new client token: AADiQVTQNg+gZmHSpSmL+KHr1HAr28PAWyipgKXGv1w0LYfWtF0EkyvnrIZJOyfQBa/DmKBhRXL6eVXugmLjZ/IJnwLxbcsy2oQJnBj+xJdOTGA5zyAOMNITgODXVnZxheoEUtjV3+N3svfSwqdXDhl4BBIU0Y5p9gmIUfg7qXX3/EtBdwEPD3BLTWy7acnqe+EWQq8z/vAyTt9/H337niDNR4KmTJSQ1XsNUttoPXua2SHMzKY9EUA="
Feb 21 08:59:14 volumio go-librespot[16907]: time="2026-02-21T08:59:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:14 volumio go-librespot[16907]: time="2026-02-21T08:59:14-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:14 volumio go-librespot[16907]: time="2026-02-21T08:59:14-06:00" level=debug msg="completed challenge"
Feb 21 08:59:14 volumio go-librespot[16907]: time="2026-02-21T08:59:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:15 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:15 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130.
Feb 21 08:59:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:17 volumio go-librespot[16929]: go-librespot daemon starting...
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="app state loaded"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=info msg="zeroconf server listening on port 36925"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="obtained new client token: AADfO7C0uZh/Bd3sS6DbbPKBQ15zzTQgaqn8c2Vjd6ga0pw4Fp0VoeaPmZLyNtNBSRXYqPDEeO1QXQROHNsmjHgHnEMSPLGOTCkPXsKd++WFtOV323CtC30o9771JJ1boI9aJeVJrorl+Ffn31kxNkl3e+JTnp8JC3RA0nIqmbgWZQ/wq1BEM5cJjXoOx7jgBgMEoY1MLqPkT1ABmUwIYYhC5oT8Mtj+bBb/MNi4Idznv1rDVeqcFQBFmQ=="
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=debug msg="completed challenge"
Feb 21 08:59:17 volumio go-librespot[16930]: time="2026-02-21T08:59:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:18 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:18 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131.
Feb 21 08:59:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:21 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:21 volumio go-librespot[16938]: go-librespot daemon starting...
Feb 21 08:59:21 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="app state loaded"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=info msg="zeroconf server listening on port 43741"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="obtained new client token: AAAhQuJW6kZuVbPYXVDSK5BTcADPy0aZ2D+SbVVJRs+2gt6+RjlqlwWGpd24Cx2RSvPRCA+i6Z9Xp6sUZ0tbh9zBldMtD2qa2qUaalgDQZstin69uUpoi8L1toCGPKfslyC+aGRPvxI+vM3z3uytLnFXju9wdob8fCtdWnl6raC6fwwsDS4MvZSOLdTwAEv+K+22iaOEvZtAwhh+oGyIyX4TA4JW5tVgn0QfP3Og2MfMkJpi1tmO8K1tyg=="
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=debug msg="completed challenge"
Feb 21 08:59:21 volumio go-librespot[16939]: time="2026-02-21T08:59:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:24 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:24 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 132.
Feb 21 08:59:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:24 volumio go-librespot[16946]: go-librespot daemon starting...
Feb 21 08:59:24 volumio go-librespot[16947]: time="2026-02-21T08:59:24-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:24 volumio go-librespot[16947]: time="2026-02-21T08:59:24-06:00" level=debug msg="app state loaded"
Feb 21 08:59:24 volumio go-librespot[16947]: time="2026-02-21T08:59:24-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:24 volumio go-librespot[16947]: time="2026-02-21T08:59:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=info msg="zeroconf server listening on port 37679"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="obtained new client token: AABApt1mHzQGoj3u5e8IlrjbqPsZZOem7hzAPtg12DPV5b7p+q0ZyMKxK9CqDJlQCWBWEFwMMX7aCXU27mYDW/jVZqR5vCHWIS9GaJ6iADNtIqcsiB4kvNYNc8d9Wwmz63ib6qKHPwMSLxnX/2TuzJIMqL95acRKJA9XZDm8bFw2X/bgrP3KAASjVwzgVzsPXz/7oKNtHET6vSlAnjxf1DjD3P6nJmUtOyKpmxBmDzvKhVWv62xlg7s="
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=debug msg="completed challenge"
Feb 21 08:59:25 volumio go-librespot[16947]: time="2026-02-21T08:59:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:27 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:27 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 133.
Feb 21 08:59:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:28 volumio go-librespot[16968]: go-librespot daemon starting...
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=debug msg="app state loaded"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:28 volumio go-librespot[16969]: time="2026-02-21T08:59:28-06:00" level=info msg="zeroconf server listening on port 35639"
Feb 21 08:59:29 volumio go-librespot[16969]: time="2026-02-21T08:59:29-06:00" level=debug msg="obtained new client token: AABukPKAalwUZd7+xV2b1/AvsdO1PJAWZtNqL6l2mHRA6pTaQtCystlB1d1hiSTgxByBE+A4ASM47hNB9Lr1dmm13xTEMMeNBj/6VY/h/siHytBiOXNryfXfJ6zCS5aiB01gdk3pBlRqtTUS85lGfuNa1y14dW05gHsmAgDYd8A+cmSfwXcCn1QCVDYH11zSevlZZYhm8TR9lSl/thDtnnpjR6SFWndSPguiR8a0yFYG2BsVLsg1PUA="
Feb 21 08:59:29 volumio go-librespot[16969]: time="2026-02-21T08:59:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:29 volumio go-librespot[16969]: time="2026-02-21T08:59:29-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:29 volumio go-librespot[16969]: time="2026-02-21T08:59:29-06:00" level=debug msg="completed challenge"
Feb 21 08:59:29 volumio go-librespot[16969]: time="2026-02-21T08:59:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:30 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:30 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 134.
Feb 21 08:59:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:32 volumio go-librespot[16980]: go-librespot daemon starting...
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="app state loaded"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=info msg="zeroconf server listening on port 45735"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="obtained new client token: AAAobGtSLIfPWIhpSsx3ItstcjFaZ6O+K3A+gpQTRA0n9pAyyGMK68uylQYYWrKAXnsdxHLowhjLNkMIr5DRaSe0zNucDTX+rKVogW+4pbNKugS9I+DzyNZK04u/CMuiPKdLP4fUtQzN4dt9L7Xw60m/Cjkhcb9iQVppS2IxS4Alj5wSDvyBUX0zxrdZqe40dQica10A5tNLLHsTTGxfyMEmukfHkDM6Mh7TPkVkqobEpkzlDvT8kL0p1g=="
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=debug msg="completed challenge"
Feb 21 08:59:32 volumio go-librespot[16981]: time="2026-02-21T08:59:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:33 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:33 volumio volumio[16198]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 135.
Feb 21 08:59:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:36 volumio go-librespot[16988]: go-librespot daemon starting...
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="app state loaded"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:36 volumio volumio[16198]: info: Initializing connection to go-librespot Websocket
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="new websocket client"
Feb 21 08:59:36 volumio volumio[16198]: info: Connection to go-librespot Websocket established
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=info msg="zeroconf server listening on port 42923"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="obtained new client token: AADB+Ns61UVUm7zAPwUuqrOEoSZiD74yXaheJRcftEVfI+x/LNHJSunNmDsrRi12zTWS/XAJcPv0a+9Y1Cad9FlUh0D8/EXqUfLnfhPIga2FZ7eoqL1OByx2dO6pnPeK8E1/0nW61am3FG08UKEOUjPJTXfNQoLAk1F0S3NX05UGnTUwJrZJmCzFJmVCYV9SXZlLKoO/t+XQTQ0pNuDAKnoILK5+uZpj4URMNENrOdEWuXr5tGjzuMjhOg=="
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="completed keyexchange"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=debug msg="completed challenge"
Feb 21 08:59:36 volumio go-librespot[16989]: time="2026-02-21T08:59:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 21 08:59:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 21 08:59:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 21 08:59:36 volumio volumio[16198]: info: Connection to go-librespot Websocket closed
Feb 21 08:59:39 volumio volumio[16198]: info: Getting Spotify volume
Feb 21 08:59:39 volumio volumio[16198]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 21 08:59:39 volumio volumio[16198]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 21 08:59:39 volumio volumio[16198]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 21 08:59:39 volumio volumio[16198]: errno: -111,
Feb 21 08:59:39 volumio volumio[16198]: code: 'ECONNREFUSED',
Feb 21 08:59:39 volumio volumio[16198]: syscall: 'connect',
Feb 21 08:59:39 volumio volumio[16198]: address: '127.0.0.1',
Feb 21 08:59:39 volumio volumio[16198]: port: 9879,
Feb 21 08:59:39 volumio volumio[16198]: response: undefined
Feb 21 08:59:39 volumio volumio[16198]: }
Feb 21 08:59:39 volumio volumio[16198]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 21 08:59:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 136.
Feb 21 08:59:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 21 08:59:39 volumio go-librespot[17022]: go-librespot daemon starting...
Feb 21 08:59:39 volumio go-librespot[17023]: time="2026-02-21T08:59:39-06:00" level=info msg="running go-librespot 0.4.0"
Feb 21 08:59:39 volumio go-librespot[17023]: time="2026-02-21T08:59:39-06:00" level=debug msg="app state loaded"
Feb 21 08:59:39 volumio go-librespot[17023]: time="2026-02-21T08:59:39-06:00" level=debug msg="stored credentials not found"
Feb 21 08:59:39 volumio go-librespot[17023]: time="2026-02-21T08:59:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 21 08:59:40 volumio sudo[17031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 08:58'
Feb 21 08:59:40 volumio sudo[17031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"