Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Proxy server started on port 33027
Mar 03 21:03:00 volumio volumio[5463]: info: Preparing to generate the ALSA configuration file
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::volumioRetrievevolume
Mar 03 21:03:00 volumio volumio[5463]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Mar 03 21:03:00 volumio volumio[5463]: info: Reading ALSA contributions from plugins.
Mar 03 21:03:00 volumio volumio[5463]: info: MPD Permissions set
Mar 03 21:03:00 volumio volumio[5463]: info: MPD Permissions set
Mar 03 21:03:00 volumio volumio[5463]: info: Spotify config file written
Mar 03 21:03:00 volumio sudo[5726]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Mar 03 21:03:00 volumio sudo[5726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:00 volumio volumio[5463]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Mar 03 21:03:00 volumio volumio[5463]: info: VolumeController:: Volume=100 Mute =false
Mar 03 21:03:00 volumio volumio[5463]: info: CoreStateMachine::pushState
Mar 03 21:03:00 volumio volumio[5463]: info: CorePlayQueue::getTrack 0
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::volumioPushState
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Server discovery started
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Player finder started
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero  2>&1 || true
Mar 03 21:03:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:00 volumio volumio[5463]: info: No need to fix Spotify hosts
Mar 03 21:03:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:00 volumio go-librespot[5729]: go-librespot daemon starting...
Mar 03 21:03:00 volumio sudo[5726]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=debug msg="app state loaded"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:00 volumio volumio[5463]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Mar 03 21:03:00 volumio volumio[5463]: info: Volumio called home
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"]
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"}
Mar 03 21:03:00 volumio volumio[5463]: 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
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-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]"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-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]"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-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]"
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=info msg="zeroconf server listening on port 38993"
Mar 03 21:03:00 volumio volumio[5463]: info: Starting Shairport Sync
Mar 03 21:03:00 volumio volumio[5463]: info: Starting Shairport Sync
Mar 03 21:03:00 volumio volumio[5463]: info: Starting Shairport Sync
Mar 03 21:03:00 volumio sudo[5750]:  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
Mar 03 21:03:00 volumio sudo[5750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:00 volumio sudo[5750]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:00 volumio volumio[5463]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Mar 03 21:03:00 volumio volumio[5463]: SPOTIFY: BQCuygQR0LcUdzKvqkSXlJ-U4iHgh1UT6SSxqv1qibxeeIAQHpFzC5NcQ1hTcbIbrquCFDbaqfAAzjqKx4c_vaOkrwmQ2lQLMwTLlLGnq77RZeX60M3HelPp-pQJPfxsK_-yYGb2noQYdpCHc9u-h0blp40U8t3o2h1-qdoPLI9ybcut9U_1fLKJl5x2t8D551w4EM9I4McrWgf9GJgF6YKexBHDIdqJKEpItU4
Mar 03 21:03:00 volumio volumio[5463]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Mar 03 21:03:00 volumio sudo[5753]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 03 21:03:00 volumio sudo[5753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:00 volumio sudo[5755]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 03 21:03:00 volumio sudo[5755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:00 volumio volumio[5463]: info: New Spotify access token = BQCuygQR0LcUdzKvqkSXlJ-U4iHgh1UT6SSxqv1qibxeeIAQHpFzC5NcQ1hTcbIbrquCFDbaqfAAzjqKx4c_vaOkrwmQ2lQLMwTLlLGnq77RZeX60M3HelPp-pQJPfxsK_-yYGb2noQYdpCHc9u-h0blp40U8t3o2h1-qdoPLI9ybcut9U_1fLKJl5x2t8D551w4EM9I4McrWgf9GJgF6YKexBHDIdqJKEpItU4
Mar 03 21:03:00 volumio volumio[5463]: info: Spotify credentials grant success - running version from March 24, 2019
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=debug msg="obtained new client token: AABh3oEO5rPU5nNxeuZ+rNilTRoyF37Mh5FznxaJl5KW+XDWh/zTz+iLZDZuCJ2He/9OIk0Zon44SIYo9zpKrv7uQMSsRCsoiLyuwyGxONKHv0yblcuzRM8Vo3WbX0DbkmaV/F/w3emZxKG6PP90pgvP+cmxU26nuKr3c7X0MpGdh/ePKAIgurv29zIwXLGrEFO5nQUfVcOn1MMSGekrvskbrxTU5OFUmlqkSxijtvrCG5URfOF/NuScPQ=="
Mar 03 21:03:00 volumio sudo[5758]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 03 21:03:00 volumio sudo[5758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-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"
Mar 03 21:03:00 volumio volumio[5463]: info: [squeezelite_mc] Executing alsactl -L -R nrestore
Mar 03 21:03:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 03 21:03:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 03 21:03:00 volumio volumio[5463]: info: Asound.conf file unchanged, so no further update is needed
Mar 03 21:03:00 volumio volumio[5463]: info: Output device has changed, restarting MPD
Mar 03 21:03:00 volumio go-librespot[5741]: time="2026-03-03T21:03:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Mar 03 21:03:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 03 21:03:00 volumio systemd[1]: shairport-sync.service: Consumed 2.015s CPU time.
Mar 03 21:03:00 volumio sudo[5764]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore
Mar 03 21:03:01 volumio sudo[5764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio volumio[5463]: info: Output device has changed, restarting Shairport Sync
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:01 volumio go-librespot[5741]: time="2026-03-03T21:03:01-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:01 volumio go-librespot[5741]: time="2026-03-03T21:03:01-06:00" level=debug msg="completed challenge"
Mar 03 21:03:01 volumio sudo[5766]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 03 21:03:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 03 21:03:01 volumio sudo[5766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio sudo[5755]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio sudo[5764]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio volumio[5463]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 03 21:03:01 volumio go-librespot[5741]: time="2026-03-03T21:03:01-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 "
Mar 03 21:03:01 volumio sudo[5766]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio sudo[5758]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio sudo[5753]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:01 volumio sudo[5772]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 03 21:03:01 volumio sudo[5772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio systemd[1]: mpd.service: Deactivated successfully.
Mar 03 21:03:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Mar 03 21:03:01 volumio systemd[1]: mpd.service: Consumed 1.594s CPU time.
Mar 03 21:03:01 volumio systemd[1]: mpd.socket: Deactivated successfully.
Mar 03 21:03:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Mar 03 21:03:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Mar 03 21:03:01 volumio volumio[5463]: info: MPD Permissions set
Mar 03 21:03:01 volumio volumio[5463]: info: Shairport-Sync Started
Mar 03 21:03:01 volumio volumio[5463]: Error adding Membership: Error: addMembership EINVAL
Mar 03 21:03:01 volumio volumio[5463]: info: Shairport-Sync Started
Mar 03 21:03:01 volumio volumio[5463]: info: Shairport-Sync Started
Mar 03 21:03:01 volumio volumio[5463]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
Mar 03 21:03:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 03 21:03:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio sudo[5798]:  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
Mar 03 21:03:01 volumio sudo[5798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:01 volumio sudo[5798]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::volumioGetState
Mar 03 21:03:01 volumio volumio[5463]: info: CorePlayQueue::getTrack 0
Mar 03 21:03:01 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload  || true
Mar 03 21:03:01 volumio volumio[5463]: info: Starting Shairport Sync
Mar 03 21:03:01 volumio sudo[5807]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Mar 03 21:03:01 volumio sudo[5807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio systemd[1]: Reloading.
Mar 03 21:03:01 volumio sudo[5809]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 03 21:03:01 volumio sudo[5809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:01 volumio volumio[5463]: 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"}
Mar 03 21:03:01 volumio volumio[5463]: info: Spotify Successfully logged in
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 03 21:03:01 volumio volumio[5463]: info: [1772593381555] CoreMusicLibrary::Adding element Spotify
Mar 03 21:03:01 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source Bandcamp Discover
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source SoundCloud
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source YouTube2
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source YouTube Music
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source Podcast
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source Volusonic
Mar 03 21:03:01 volumio volumio[5463]: Cannot find translation for source Spotify
Mar 03 21:03:01 volumio sudo[5799]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 03 21:03:01 volumio sudo[5799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 03 21:03:01 volumio sudo[5799]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:02 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:02 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:02 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:02 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 03 21:03:03 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.
Mar 03 21:03:03 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.
Mar 03 21:03:03 volumio sudo[5807]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:03 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:03 volumio sudo[5833]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:03 volumio sudo[5833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:03 volumio sudo[5833]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:03 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true
Mar 03 21:03:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 03 21:03:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 03 21:03:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 03 21:03:03 volumio systemd[1]: shairport-sync.service: Consumed 1.496s CPU time.
Mar 03 21:03:03 volumio sudo[5837]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite
Mar 03 21:03:03 volumio sudo[5837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 03 21:03:03 volumio sudo[5809]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:03 volumio volumio[5463]: info: Shairport-Sync Started
Mar 03 21:03:03 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator...
Mar 03 21:03:03 volumio volumio[5463]: info: go-librespot daemon successfully initialized
Mar 03 21:03:03 volumio systemd[1]: squeezelite.service: Deactivated successfully.
Mar 03 21:03:03 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator.
Mar 03 21:03:03 volumio sudo[5837]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74.
Mar 03 21:03:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:04 volumio go-librespot[5853]: go-librespot daemon starting...
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="app state loaded"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:04 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:04 volumio sudo[5861]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:04 volumio sudo[5861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:04 volumio sudo[5861]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:04 volumio volumio[5463]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log
Mar 03 21:03:04 volumio sudo[5865]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log
Mar 03 21:03:04 volumio sudo[5865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:04 volumio sudo[5865]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:04 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true
Mar 03 21:03:04 volumio sudo[5869]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite
Mar 03 21:03:04 volumio sudo[5869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-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]"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-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]"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-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]"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=info msg="zeroconf server listening on port 38441"
Mar 03 21:03:04 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator...
Mar 03 21:03:04 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator.
Mar 03 21:03:04 volumio sudo[5869]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="obtained new client token: AACogkEKBc9mURoqpFrP28SceuSTceSFsLPVmK05xaXFZI/bSnAq83bzdLUHycfk5JslqCWjtQgRRFH0/vJmi6QS+JqHVYdNHai6lH9Ioav5dtErT+oyNrwio0gWgefiGRh6uNes2WJ8PyX7ZMrOtrjNz0MCJqn+Z8+q4PF6uwfuGu+8bbFeDKhGk4lbgefWuDu8Kvmk5FeBX4MoN1HJ5JJjcwZk2lhvxCQPMs24sPlPClXTMOjzqB0iGw=="
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-06:00" level=debug msg="completed challenge"
Mar 03 21:03:04 volumio go-librespot[5854]: time="2026-03-03T21:03:04-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 "
Mar 03 21:03:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:05 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:05 volumio sudo[5890]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:05 volumio sudo[5890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:05 volumio sudo[5890]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:05 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:05 volumio sudo[5893]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:05 volumio sudo[5893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:05 volumio sudo[5893]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:06 volumio mpd[5831]: 2026-03-03T21:03:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 03 21:03:06 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:06 volumio sudo[5896]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:06 volumio sudo[5896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:06 volumio sudo[5896]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:06 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Mar 03 21:03:06 volumio sudo[5691]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:06 volumio sudo[5772]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:06 volumio sudo[5679]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:06 volumio volumio[5463]: error: MPD error: The expression evaluated to a falsy value:
Mar 03 21:03:06 volumio volumio[5463]:   assert.ok(self.idling)
Mar 03 21:03:06 volumio volumio[5463]: error: The expression evaluated to a falsy value:
Mar 03 21:03:06 volumio volumio[5463]:   assert.ok(self.idling)
Mar 03 21:03:06 volumio volumio[5463]: error: MPD error: The expression evaluated to a falsy value:
Mar 03 21:03:06 volumio volumio[5463]:   assert.ok(self.idling)
Mar 03 21:03:06 volumio volumio[5463]: error: The expression evaluated to a falsy value:
Mar 03 21:03:06 volumio volumio[5463]:   assert.ok(self.idling)
Mar 03 21:03:06 volumio volumio[5463]: info: MPD running with PID5831
Mar 03 21:03:06 volumio volumio[5463]:  ,establishing connection
Mar 03 21:03:06 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:06 volumio volumio[5463]: error: updateQueue error: null
Mar 03 21:03:06 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:06 volumio volumio[5463]: error: updateQueue error: null
Mar 03 21:03:07 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:07 volumio sudo[5903]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:07 volumio sudo[5903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:07 volumio sudo[5903]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:07 volumio volumio[5463]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Mar 03 21:03:07 volumio sudo[5906]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Mar 03 21:03:07 volumio sudo[5906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:07 volumio sudo[5906]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:07 volumio volumio[5463]: info: Completed starting Core Plugins
Mar 03 21:03:07 volumio volumio[5463]: info: -------------------------------------------
Mar 03 21:03:07 volumio volumio[5463]: info: -----    MyVolumio plugins startup     ----
Mar 03 21:03:07 volumio volumio[5463]: info: -------------------------------------------
Mar 03 21:03:07 volumio volumio[5463]: info: [MyVolumio PluginManager] Fetching plans data....
Mar 03 21:03:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75.
Mar 03 21:03:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:08 volumio go-librespot[5908]: go-librespot daemon starting...
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="app state loaded"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-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]"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-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]"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-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]"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=info msg="zeroconf server listening on port 37633"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="obtained new client token: AAALUJ+gL9qUq0IoWpbFxPPxGREKJP8Nl1bGAzaXT+1q+9YZmEIcImwwyvuFLUGPyO3zlRLHjHjgKlRTw0FB9L3x/VZV+0C4shBdbyUi/Cpd13OHJL9b5yq6hnNkJ/WnCiSfK4qzCsQHb9Myi6rTz7wsTCZ2+x60zYfrKX4Cwl3YQYYy4Y2VXTG6sEMycMDpG8/wFBNhQiNkBGzVVPgDUt6fTbw/LkZKgYEFepb3ZDo+HZe3bMPaIi+0Ug=="
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-06:00" level=debug msg="completed challenge"
Mar 03 21:03:08 volumio go-librespot[5909]: time="2026-03-03T21:03:08-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 "
Mar 03 21:03:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:09 volumio sudo[5919]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 03 21:03:09 volumio sudo[5919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:09 volumio sudo[5921]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 03 21:03:09 volumio sudo[5921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:09 volumio sudo[5924]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 03 21:03:09 volumio sudo[5924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:09 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Mar 03 21:03:09 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Mar 03 21:03:09 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Mar 03 21:03:09 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:09 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:09 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Mar 03 21:03:09 volumio sudo[5924]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:09 volumio sudo[5921]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:09 volumio sudo[5919]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:09 volumio mpd_monitor.sh[5927]: MPD Monitor Service: Starting MPD Monitor Service
Mar 03 21:03:09 volumio volumio[5463]: info: Successfully started MPD Monitor
Mar 03 21:03:09 volumio volumio[5463]: info: Successfully started MPD Monitor
Mar 03 21:03:09 volumio volumio[5463]: info: Successfully started MPD Monitor
Mar 03 21:03:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76.
Mar 03 21:03:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:11 volumio go-librespot[5931]: go-librespot daemon starting...
Mar 03 21:03:11 volumio go-librespot[5932]: time="2026-03-03T21:03:11-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:11 volumio go-librespot[5932]: time="2026-03-03T21:03:11-06:00" level=debug msg="app state loaded"
Mar 03 21:03:11 volumio go-librespot[5932]: time="2026-03-03T21:03:11-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-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]"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-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]"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-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]"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=info msg="zeroconf server listening on port 38209"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=debug msg="obtained new client token: AAB7CCIFum7b7+EP+QJ1Z0G6gTdWs/SZVuwrdKUreyRUeHToGAYo6QLbutAnpSc9iUbdOqsGZpI4KP78ZjtDnbQCH+ds62cO+D75aoZ0Bqdu/YdJh5PORLMVLDqcn3l72jDxePfI1RXZ/GhUUvSChZa2dH8hV1ih/qYVo+2tBbuLvpnxvcxId1jFlCsoyCQ9JKoDQCrbvd+H9STtrAWaSb8NQ2xUH2S99l7hUEdO2drb3WG9t7S/WInG5w=="
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-06:00" level=debug msg="completed challenge"
Mar 03 21:03:12 volumio go-librespot[5932]: time="2026-03-03T21:03:12-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 "
Mar 03 21:03:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:14 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:14 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77.
Mar 03 21:03:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:15 volumio go-librespot[5953]: go-librespot daemon starting...
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-06:00" level=debug msg="app state loaded"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin multiroom to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 03 21:03:15 volumio volumio[5463]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-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]"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-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]"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-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]"
Mar 03 21:03:15 volumio go-librespot[5954]: time="2026-03-03T21:03:15-06:00" level=info msg="zeroconf server listening on port 42909"
Mar 03 21:03:16 volumio go-librespot[5954]: time="2026-03-03T21:03:16-06:00" level=debug msg="obtained new client token: AAAuOeWXL5zaTh2V3RREP6TQTpvxnZ5b8Ypi65SwaFJ63/OSCn2YAHGpcAdLrFwb1jxc4Phwbx8Np6UttrbXKF5Jho68z7cyLuMAQtKsSWvJQAwX2hdYJrF7sHBTY/TiW23CHt4tC7wjVBRFIaumte7ov7JhraTQz2KhYQ1UPrgeHoR7mXOy66siG3rarwl40wHDh9Btnwe3HtNFwRXDtxCrfqcSbSM2xrt5BIHyfrHyMqDp9+d/f1A="
Mar 03 21:03:16 volumio go-librespot[5954]: time="2026-03-03T21:03:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:16 volumio go-librespot[5954]: time="2026-03-03T21:03:16-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:16 volumio go-librespot[5954]: time="2026-03-03T21:03:16-06:00" level=debug msg="completed challenge"
Mar 03 21:03:16 volumio go-librespot[5954]: time="2026-03-03T21:03:16-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 "
Mar 03 21:03:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:16 volumio volumio[5463]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 03 21:03:16 volumio volumio[5463]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 03 21:03:16 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:16 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:16 volumio volumio[5463]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 03 21:03:16 volumio volumio[5463]: info: MyVolumio login type: Token
Mar 03 21:03:16 volumio volumio[5463]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 03 21:03:16 volumio volumio[5463]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 03 21:03:17 volumio volumio[5463]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 03 21:03:17 volumio volumio[5463]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 03 21:03:17 volumio volumio[5463]: info: Streaming services startup
Mar 03 21:03:17 volumio volumio[5463]: info: Starting Streaming Daemon
Mar 03 21:03:17 volumio sudo[5962]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 03 21:03:17 volumio sudo[5962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 03 21:03:17 volumio volumio[5463]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 03 21:03:17 volumio sudo[5962]: pam_unix(sudo:session): session closed for user root
Mar 03 21:03:17 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:17 volumio volumio[5463]: error: Cannot start Volumio Streaming Daemon
Mar 03 21:03:17 volumio volumio[5463]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 03 21:03:17 volumio volumio[5463]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 03 21:03:17 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:18 volumio volumio[5463]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required.
Mar 03 21:03:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78.
Mar 03 21:03:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:19 volumio go-librespot[5968]: go-librespot daemon starting...
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="app state loaded"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-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]"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-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]"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-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]"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=info msg="zeroconf server listening on port 37887"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="obtained new client token: AADwRLyaHbySvYaBO4ZAmvzn/pfGLt/mfP0jkPQ1jw6G8CwbqMLoWIX1e6kCDu7OGO/qfHj11GrIal43UdjaphTzdv0wJejLUiIcN8o7WwsDTlBvJtrtMxGaoLd/uOmecT+Y5O2xU0R4ggdQnaN96uVxFEHYA/8DAuGL5uvkPWlP5myqeWlXSGbC9fRlzXTgI++RKicp4Pn7hnaraDxDWWAadhGDyo1AplJigRlNLqRVUg2MwgbFHXeZqQ=="
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-06:00" level=debug msg="completed challenge"
Mar 03 21:03:19 volumio go-librespot[5969]: time="2026-03-03T21:03:19-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 "
Mar 03 21:03:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:20 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:20 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79.
Mar 03 21:03:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:23 volumio go-librespot[5976]: go-librespot daemon starting...
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="app state loaded"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-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]"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-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]"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-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]"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=info msg="zeroconf server listening on port 45475"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="obtained new client token: AACvYGrqXPp4gJcvn1txqEUQOwvumbPg+6+BRzS7WbYfRvaevOa5eJrSe1vaqv+Sl5nJdL1VDVMrMzDA+CkreXxUJAJhq14p1uF2ezoDK0zoLOTzEiB4sC6bvNCSOcRSxp+1np3Jen3Lu7gc8pQ4ruKC611d446zo2veJgONQVTZzh0/vAOWiYHrTTaTMdVzSCriSFpyzBdgiDVwNOpxuq05pUNCxZTDFCaEp5X6EZWygmYpIXVGEMf3DA=="
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-06:00" level=debug msg="completed challenge"
Mar 03 21:03:23 volumio go-librespot[5977]: time="2026-03-03T21:03:23-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 "
Mar 03 21:03:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:23 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:23 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80.
Mar 03 21:03:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:26 volumio go-librespot[5998]: go-librespot daemon starting...
Mar 03 21:03:26 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:26 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:26 volumio go-librespot[5999]: time="2026-03-03T21:03:26-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:26 volumio go-librespot[5999]: time="2026-03-03T21:03:26-06:00" level=debug msg="app state loaded"
Mar 03 21:03:26 volumio go-librespot[5999]: time="2026-03-03T21:03:26-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:26 volumio go-librespot[5999]: time="2026-03-03T21:03:26-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-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]"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-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]"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-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]"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-06:00" level=info msg="zeroconf server listening on port 38285"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-06:00" level=debug msg="obtained new client token: AADNO1nLjQ+CmuquZcN//0Dy/ru8EODQGlComp1Zw/EemEKZKhxOoUxQYvs+VOOU97SkTmWNrf+i8Z72SH1Dyqgp7yL0aPYaiE2hijiweKLaNxTL1jh9Cd1xLV3jJdSa4SxX1XUMF9kNF3FeF8Z/ERcZ35NDDgBgfAqCuylUHeKZee6dWV5FMiXV4lNtSsyQKtruWxOJKk+SwvGDMdBsMEe+liBOxHrGTquXuJp1IjtSy8BWwfhbMuc="
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-06:00" level=debug msg="completed challenge"
Mar 03 21:03:27 volumio go-librespot[5999]: time="2026-03-03T21:03:27-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 "
Mar 03 21:03:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:29 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 03 21:03:29 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:29 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 03 21:03:29 volumio volumio-remote-updater[13697]: Test mode disabled
Mar 03 21:03:29 volumio volumio-remote-updater[13697]: Alpha mode disabled
Mar 03 21:03:29 volumio volumio-remote-updater[13697]: Alpha legacy test mode disabled
Mar 03 21:03:29 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 03 21:03:29 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:29 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:30 volumio volumio[5463]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for CPU utilization spike
\nFix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\nAdd BLE onboarding plugin
\nEnable vc4-kms-v3d for all compatible RPi models
\nUpdate Tidal SDK
\n\n","title":"Update v4.096","updateavailable":true}
Mar 03 21:03:30 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 03 21:03:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81.
Mar 03 21:03:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:30 volumio go-librespot[6013]: go-librespot daemon starting...
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-06:00" level=debug msg="app state loaded"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-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]"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-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]"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-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]"
Mar 03 21:03:30 volumio go-librespot[6014]: time="2026-03-03T21:03:30-06:00" level=info msg="zeroconf server listening on port 42243"
Mar 03 21:03:31 volumio go-librespot[6014]: time="2026-03-03T21:03:31-06:00" level=debug msg="obtained new client token: AAAoMW8RO6cfCdR2ZXk8zojfIQ4Keu2xPrEmGfIVvfoKOxgLr7v9bDR98EVTK8B1LDjjJT5Gl1qU8lOVPN8YZB7NVX1CUE7hZc4Ld8kdEIYtWEbqZT7mYdZNKIDrZ7ESvwVUv8EIkeefdsFmX41el17N2MrTzTrIB27Qf0Rjd5Gmz+bzGoZw4Lo+LD0hdJCJzJMFPwqfdPuFq3weyRdj7Y8SXX5Cjb/OInaHQSVgfbmO2xZ6XTlDRCQ="
Mar 03 21:03:31 volumio go-librespot[6014]: time="2026-03-03T21:03:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:31 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 03 21:03:31 volumio volumio[5463]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Mar 03 21:03:31 volumio volumio[5463]: info: CoreCommandRouter::volumioGetState
Mar 03 21:03:31 volumio volumio[5463]: info: CorePlayQueue::getTrack 0
Mar 03 21:03:32 volumio go-librespot[6014]: time="2026-03-03T21:03:32-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:32 volumio go-librespot[6014]: time="2026-03-03T21:03:32-06:00" level=debug msg="completed challenge"
Mar 03 21:03:32 volumio go-librespot[6014]: time="2026-03-03T21:03: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 "
Mar 03 21:03:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:32 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:32 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82.
Mar 03 21:03:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:35 volumio go-librespot[6036]: go-librespot daemon starting...
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="app state loaded"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-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]"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-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]"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-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]"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=info msg="zeroconf server listening on port 40569"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="obtained new client token: AADOPArgBWT9dtjgHkkYN1HYif3H/Sls98KPS6XzGH+jUgyOhx+xJPeo58X+reajFuHiHd3vsyc9HOQa+oadPFSbcLcAfp5HhmOC2EsgPPSqoLbIZdnlqXr2sh6gBzl25hMeYZMxmWeYcDS9ozzPZlu3eOpGOtk5Dn88TrigBt8Z9q3eqyvucI+jBDAacwLwjclYkzfLaM4/y+Wu/cqcNq2AjOfqzMEmLyANGlrKqvIlllE29f7Bm/lbEw=="
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-06:00" level=debug msg="completed challenge"
Mar 03 21:03:35 volumio go-librespot[6037]: time="2026-03-03T21:03:35-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 "
Mar 03 21:03:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:35 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:35 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 03 21:03:36 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 03 21:03:37 volumio volumio[5463]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Mar 03 21:03:37 volumio volumio[5463]: info: Completed starting MyVolumio Plugin
Mar 03 21:03:37 volumio volumio[5463]:  [Metrics]  CommandRouter:  54s 961.06ms 
Mar 03 21:03:37 volumio volumio[5463]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 03 21:03:37 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 03 21:03:37 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 03 21:03:37 volumio volumio[5463]: info: CoreCommandRouter::Close All Modals sent
Mar 03 21:03:37 volumio volumio[5463]: info: CoreCommandRouter::Close All Modals sent
Mar 03 21:03:38 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 03 21:03:38 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 03 21:03:38 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 03 21:03:38 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:38 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83.
Mar 03 21:03:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:39 volumio go-librespot[6050]: go-librespot daemon starting...
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="app state loaded"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-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]"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-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]"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-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]"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=info msg="zeroconf server listening on port 39485"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="obtained new client token: AABEI8B7qVwJDkjIDIDLkhVUFegrC6MhNBsKSj4FHvrlzoJ/MEm8hgDRHWMQBuxLWeCNXTQV5A/fiKlpYk7FAeAbGceog525DsxydIJzHmiNDDO31DoBOY5ZlUxcE9+4z7BdY7mE2cSpkDzLVKpOsy0yFRhX0/gWcGfVUZOf9udn/0w+VB8XeFcwHpgjoep5d5wkBKWdx7f1k1D5zaB8Writ4Wxja9O2+z3VjVlTrFaxXNLW8N7xEQJPZg=="
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-06:00" level=debug msg="completed challenge"
Mar 03 21:03:39 volumio go-librespot[6051]: time="2026-03-03T21:03:39-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 "
Mar 03 21:03:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:41 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:41 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84.
Mar 03 21:03:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:42 volumio go-librespot[6058]: go-librespot daemon starting...
Mar 03 21:03:42 volumio go-librespot[6059]: time="2026-03-03T21:03:42-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:42 volumio go-librespot[6059]: time="2026-03-03T21:03:42-06:00" level=debug msg="app state loaded"
Mar 03 21:03:42 volumio go-librespot[6059]: time="2026-03-03T21:03:42-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:42 volumio go-librespot[6059]: time="2026-03-03T21:03:42-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03: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]"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03: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]"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03: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]"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-06:00" level=info msg="zeroconf server listening on port 42679"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-06:00" level=debug msg="obtained new client token: AADmIv4JihNhxYRmvrBX/GiLRPay/zpg3sxEQoZPQRemLmO/yqQEKbtdHq6N8xMuAUamNDbKkGCrnsdiEkXpLV7rgOtkT+/kKltcMz26OaYes2IJa0SLS1CHbaYIonRUpSr/kA13UJq5tElIzqvnMkte05tOGT0+5jzIx++/1ukfrzVh14hScAlegsomWqX4idf+mskNa8e9emLe65lnRxN0kgj1m0C8Re0/2rYxkgRWrT+kqCnPYCs4mQ=="
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-06:00" level=debug msg="completed challenge"
Mar 03 21:03:43 volumio go-librespot[6059]: time="2026-03-03T21:03:43-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 "
Mar 03 21:03:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:44 volumio volumio[5463]: info: BOOT COMPLETED
Mar 03 21:03:44 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:44 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85.
Mar 03 21:03:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:46 volumio go-librespot[6080]: go-librespot daemon starting...
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-06:00" level=debug msg="app state loaded"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-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]"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-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]"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-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]"
Mar 03 21:03:46 volumio go-librespot[6081]: time="2026-03-03T21:03:46-06:00" level=info msg="zeroconf server listening on port 37697"
Mar 03 21:03:47 volumio go-librespot[6081]: time="2026-03-03T21:03:47-06:00" level=debug msg="obtained new client token: AAD4E8uR6GnobYb3n3zXiuOoqJIJpmLixxG+x1wyLh3hnk5SFyzQ0BkxGE9ipIEnj2SmxwSaAJxKhHMYHw4XjO0yz5glJZOaqqHZhA5+Q24xmLcIdxYzQgmztyCa8tgnSMni2E966W4Ujcgk/sksYWDx4ZW3z5B1bo+mmuSBLuEH399kiYgYuWTHF8hG/2PoMl6EFJu+nr85le7Iw2O0TYKCkNeB8KA1nQZrqejmX6ZQBPkNwgimQE4="
Mar 03 21:03:47 volumio go-librespot[6081]: time="2026-03-03T21:03:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:47 volumio go-librespot[6081]: time="2026-03-03T21:03:47-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:47 volumio go-librespot[6081]: time="2026-03-03T21:03:47-06:00" level=debug msg="completed challenge"
Mar 03 21:03:47 volumio go-librespot[6081]: time="2026-03-03T21:03: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 "
Mar 03 21:03:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:47 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:47 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86.
Mar 03 21:03:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:50 volumio go-librespot[6091]: go-librespot daemon starting...
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="app state loaded"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-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]"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-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]"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-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]"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=info msg="zeroconf server listening on port 43087"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="obtained new client token: AADQCSBuERHAaHS4BTammRDYYzetVIe61dNfiCG6Dk1ibdPy0p9gk/Jrz0rwULqNyA2DSMZJZ5gdQLn0xlF1YmiQBVkZfpGF1FPdxDCkI1LGwcAo40JybG2FxO7oyunS0SdA/eLzsyg08joPxS/osL+rP0yFI9iHe+xWcJHm9YyzPH9xL+kntbxD4d9A78L2Lg4i7h99FM34t4DBpzQonNnzsfMHnfqwyEFXf+XuAUpertII6csyIuTJCg=="
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-06:00" level=debug msg="completed challenge"
Mar 03 21:03:50 volumio go-librespot[6092]: time="2026-03-03T21:03:50-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 "
Mar 03 21:03:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:50 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:50 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:54 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:54 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87.
Mar 03 21:03:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:54 volumio go-librespot[6100]: go-librespot daemon starting...
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="app state loaded"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-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]"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-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]"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-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]"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=info msg="zeroconf server listening on port 46103"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="obtained new client token: AACV1PPVpMnmkOt8TOef0kpn4o+2bh/GXN6dw3Q4m09Yk+PXlf3aM+qOSSbqiIAAzbiDWQgL2yaWDWeM1XS+uR4/vQz2nAsBZvrC0eXrBpP6h49j0vdFXjCx64bjwLo0qNJoLAn/oi0ajEdMAyFyi+3cYg4LnA4qS90+iJ0q+Rak7lc5Coh0XoP8cTYkqfjCAvHkrhvUkDTzkjtSvvOtsqLXkdpP7gl+QqUxlWEs/XmH/Sl9pQREulIbIA=="
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-06:00" level=debug msg="completed challenge"
Mar 03 21:03:54 volumio go-librespot[6101]: time="2026-03-03T21:03:54-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 "
Mar 03 21:03:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:57 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:03:57 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:03:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88.
Mar 03 21:03:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:03:57 volumio go-librespot[6122]: go-librespot daemon starting...
Mar 03 21:03:57 volumio go-librespot[6123]: time="2026-03-03T21:03:57-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:03:57 volumio go-librespot[6123]: time="2026-03-03T21:03:57-06:00" level=debug msg="app state loaded"
Mar 03 21:03:57 volumio go-librespot[6123]: time="2026-03-03T21:03:57-06:00" level=debug msg="stored credentials not found"
Mar 03 21:03:57 volumio go-librespot[6123]: time="2026-03-03T21:03:57-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03: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-gew4.spotify.com:80]"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-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]"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-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]"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-06:00" level=info msg="zeroconf server listening on port 40919"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-06:00" level=debug msg="obtained new client token: AAAn9/1PBSOPw7zzaEfGGDiWzawDqtlIGFzQV5mH6uZkFK+Vq45SmOegNE3MGjPg6+PKJE6KrXrw3g3N1X8wNJsGmd/7PZUg10gtjE/IBRveFggrDMTC+BnxIgYu4JJzZqwzxU3oMuL6qn1OZ5FLJytDEXOHHzpiEC9HUUxw9vlhDnEaYD7GrWKb0nW4ntYeQaw60OmYhieezVl+JfJ5wKv5RYyVb5ZcC2Uzo5+P9v1PQei8TgMov/ezuA=="
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-06:00" level=debug msg="completed keyexchange"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-06:00" level=debug msg="completed challenge"
Mar 03 21:03:58 volumio go-librespot[6123]: time="2026-03-03T21:03:58-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 "
Mar 03 21:03:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:03:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:03:59 volumio volumio[5463]: info: UPDATER: Scheduling automatic update
Mar 03 21:03:59 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 03 21:03:59 volumio volumio[5463]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 03 21:03:59 volumio volumio[5463]: info: UPDATER: Auto update will take place at: Wed Mar 04 2026 05:21:06 GMT-0600 (Central Standard Time)
Mar 03 21:04:00 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:00 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89.
Mar 03 21:04:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:01 volumio go-librespot[6130]: go-librespot daemon starting...
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-06:00" level=debug msg="app state loaded"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-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]"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-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]"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-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]"
Mar 03 21:04:01 volumio go-librespot[6131]: time="2026-03-03T21:04:01-06:00" level=info msg="zeroconf server listening on port 35337"
Mar 03 21:04:02 volumio go-librespot[6131]: time="2026-03-03T21:04:02-06:00" level=debug msg="obtained new client token: AACb2oBDaHlwvYPdzfjECA1MF6QlJm97WLmWfYE5RtTHrQSzLrSz78CAYG5ckVh2bE62hln1EdSfCIiVsTPUS6/CpFFSDydbWpZXrFN5D+ykY81AXNB94nKxUzMzN6uU4Uw55G0/9CElGTVpdibCzttYNeidQJLUh26HT9wMr+kW+8/YQk071oUNubTVBgZ361oPpzOcaAxgllrPkx/CDs0A5040tO6Mx91Y7i4hbYKmzIQDdIjl7C0="
Mar 03 21:04:02 volumio go-librespot[6131]: time="2026-03-03T21:04:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:02 volumio go-librespot[6131]: time="2026-03-03T21:04:02-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:02 volumio go-librespot[6131]: time="2026-03-03T21:04:02-06:00" level=debug msg="completed challenge"
Mar 03 21:04:02 volumio go-librespot[6131]: time="2026-03-03T21:04: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 "
Mar 03 21:04:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:03 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:03 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90.
Mar 03 21:04:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:05 volumio go-librespot[6152]: go-librespot daemon starting...
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="app state loaded"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-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]"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-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]"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-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]"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=info msg="zeroconf server listening on port 35487"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="obtained new client token: AABSi72OUWt2a7gdypPy0Tbp2sPjdNFH3BxrcCOr6dO6C9lFJfl2iYzeo/jehsogsVn80Rfmqt71Pzj3YGM7OB57/Q1JW/jX3ZqimIPT6mF1BM2qvs3cxE9Y6Db8ZdDyE/slIWeuN9fyRJ2G3PXPz68Tc3/NOelQTSspdcFEHcd5GaLVv7qPyiV9vXF9oHkEMK49EjLjCVOZQ9jBNm0ICVB8n4KkbDD4v6SNAbvBNy/zPIe+WZQ1XtTqhg=="
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-06:00" level=debug msg="completed challenge"
Mar 03 21:04:05 volumio go-librespot[6153]: time="2026-03-03T21:04:05-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 "
Mar 03 21:04:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:06 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:06 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:09 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:09 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91.
Mar 03 21:04:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:09 volumio go-librespot[6160]: go-librespot daemon starting...
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="app state loaded"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-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]"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-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]"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-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]"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=info msg="zeroconf server listening on port 40591"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="obtained new client token: AACOD3TiX9wtMx1Jvjv3fPaKK93wvhKw+JCxoT2KacWrLrqy9A0ZFLClGJmM1cRYpTqwrqS6C8K3tTuYzprTjJBkfyW0/nFPQqljBeWlku5As5o2qoCm5ffbbXQcTM4NYEP91BpHUwqUHd8bMMX0JgDBlRj6C/S67pBcyihVNStO/IFC7zu91Wrg527Fa5or0aixO46xgRFuhr8MieG9B9/fP4Hf+HSBo6Hme1CxJeERJbtyclHVMM+Uhw=="
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-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"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-06:00" level=debug msg="completed challenge"
Mar 03 21:04:09 volumio go-librespot[6161]: time="2026-03-03T21:04:09-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 "
Mar 03 21:04:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:12 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:12 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92.
Mar 03 21:04:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:12 volumio go-librespot[6173]: go-librespot daemon starting...
Mar 03 21:04:12 volumio go-librespot[6174]: time="2026-03-03T21:04:12-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:12 volumio go-librespot[6174]: time="2026-03-03T21:04:12-06:00" level=debug msg="app state loaded"
Mar 03 21:04:12 volumio go-librespot[6174]: time="2026-03-03T21:04:12-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:12 volumio go-librespot[6174]: time="2026-03-03T21:04:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-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]"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-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]"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-06:00" level=info msg="zeroconf server listening on port 37321"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-06:00" level=debug msg="obtained new client token: AADcW9HCVdqeH2WJtL3IhFu2fAeLrmve6dPyl3rDcpSWFfrd8QWaCXm9fRKSO7bLL11ToMm7fFtBOlSlDYsB3F9OfPC8q3AgDEaPUZoHpo1r7KkYVueQjjIoeg4WuyVQ/2xB7IQ0UB/Izvw5ZhW/UdHmfcooI5YEgjVBxPBxopKUjb2v7EMfMea9HjbPmlYE1wXnJmue//Fd/FZE54f+oxYCtjY0EbTK1YZY+XIJC/xiQMscoWeX8tp26Q=="
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-06:00" level=debug msg="completed challenge"
Mar 03 21:04:13 volumio go-librespot[6174]: time="2026-03-03T21:04:13-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 "
Mar 03 21:04:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:15 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:15 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93.
Mar 03 21:04:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:16 volumio go-librespot[6195]: go-librespot daemon starting...
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-06:00" level=debug msg="app state loaded"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-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]"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-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]"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-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]"
Mar 03 21:04:16 volumio go-librespot[6196]: time="2026-03-03T21:04:16-06:00" level=info msg="zeroconf server listening on port 35553"
Mar 03 21:04:17 volumio go-librespot[6196]: time="2026-03-03T21:04:17-06:00" level=debug msg="obtained new client token: AACzNa2tWmW1orcBLqQMPze1pJC361t167cD5krYpLoMg42BQMO3nxxWtbzuz4gyoUcVCvNTJEVeEjOJLZrzdRplI9IDqmuvALli2pbnhxum2N3P+ZuF9+0ccmuyV0ej3PQIaXMS0yJyPT0Ujg3vvjN+W9Z/mQfjh3FtOqw0kbz+c2zATupVpZRSm+V6ZGTKN1FKXvhK89gAY82hKqs5OerkiJ2+TaHoOr4u/WvlFYFIBCz2ShtAUtY="
Mar 03 21:04:17 volumio go-librespot[6196]: time="2026-03-03T21:04:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:17 volumio go-librespot[6196]: time="2026-03-03T21:04:17-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:17 volumio go-librespot[6196]: time="2026-03-03T21:04:17-06:00" level=debug msg="completed challenge"
Mar 03 21:04:17 volumio go-librespot[6196]: time="2026-03-03T21:04: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 "
Mar 03 21:04:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:18 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:18 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94.
Mar 03 21:04:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:20 volumio go-librespot[6203]: go-librespot daemon starting...
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="app state loaded"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-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]"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-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]"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-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]"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=info msg="zeroconf server listening on port 45165"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="obtained new client token: AACJ2wjLJnIVm7KGo/pSXm1OnZKM77lm4GML89stcBELrbSbbPVvH8HwAqIXO9+3SJoNLZqG1oZt+2DahXyMsDBSA0hwMAs9Ut4fMWzhrTU60gbyvyLrG6uXOtQLCH5IoIfhssLlYb6//jLy/ig13F8qd4M4wewn5YzS7lGcyYbNoh2Vs+Jr9lg/Xv269jMdhMuLG+Qet5HivTNwiYeVis63i3nAt1SR1Y9jewMToGaRhQAyjxdQVHJMbg=="
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-06:00" level=debug msg="completed challenge"
Mar 03 21:04:20 volumio go-librespot[6204]: time="2026-03-03T21:04:20-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 "
Mar 03 21:04:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:21 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:21 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:24 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:24 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95.
Mar 03 21:04:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:24 volumio go-librespot[6212]: go-librespot daemon starting...
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="app state loaded"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-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]"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-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]"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-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]"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=info msg="zeroconf server listening on port 33309"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="obtained new client token: AACCOWgLQCGxnXpcCttyAaOnpUPm1gkj8e1Kl0c8CPV/8jC1xQRmRlOX4DfU72Yzt1VlJLPRNzUZCT4CbJZ1lmRCqBKlaj6kLEz1gBNC0ApfaYUHbK8BqwSTlyAoar3GXoqvA97JiI7BcDXB8JDNbFxSDcDPqc7Te6BMvxZW1RgPuFW0AyMAFVs1tpdE3svJqWscx8BqXbOkffnKSTw2EGu7QZcXTE4NnPjPAgjXxweSmfe69/g208qIFw=="
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-06:00" level=debug msg="completed challenge"
Mar 03 21:04:24 volumio go-librespot[6213]: time="2026-03-03T21:04:24-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 "
Mar 03 21:04:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:27 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:27 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96.
Mar 03 21:04:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:27 volumio go-librespot[6234]: go-librespot daemon starting...
Mar 03 21:04:27 volumio go-librespot[6235]: time="2026-03-03T21:04:27-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:27 volumio go-librespot[6235]: time="2026-03-03T21:04:27-06:00" level=debug msg="app state loaded"
Mar 03 21:04:27 volumio go-librespot[6235]: time="2026-03-03T21:04:27-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:27 volumio go-librespot[6235]: time="2026-03-03T21:04:27-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-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]"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-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]"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-06:00" level=info msg="zeroconf server listening on port 39645"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-06:00" level=debug msg="obtained new client token: AABY+gcw9noMuxiV/5vyEeWNhFDbKDF8Kkj9D0kyoB0+5I7Z24IYImZlPKAeSsNyeje7nS4VtOCP9zQAXrxylGgsbiWlHJgisGZ095PDBLLh/EUN56EGHdO6hWQ4ATpb7VBIuLgc4kPyt2XrFtPzvCLIf1GH7N4pHjXJsMAjTR4s100t0y+Ch+0oogkQmYtRI6IFacuNalN52hsuiEsXt2j1HEGYhp6VmF4jAIdK4ibf+t+Z1d9YOPwZAA=="
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-06:00" level=debug msg="completed challenge"
Mar 03 21:04:28 volumio go-librespot[6235]: time="2026-03-03T21:04:28-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 "
Mar 03 21:04:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:30 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:30 volumio volumio[5463]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 03 21:04:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97.
Mar 03 21:04:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 03 21:04:31 volumio go-librespot[6246]: go-librespot daemon starting...
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-06:00" level=info msg="running go-librespot 0.4.0"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-06:00" level=debug msg="app state loaded"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-06:00" level=debug msg="stored credentials not found"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-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]"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-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]"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-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]"
Mar 03 21:04:31 volumio go-librespot[6247]: time="2026-03-03T21:04:31-06:00" level=info msg="zeroconf server listening on port 40421"
Mar 03 21:04:33 volumio volumio[5463]: info: Initializing connection to go-librespot Websocket
Mar 03 21:04:33 volumio go-librespot[6247]: time="2026-03-03T21:04:33-06:00" level=debug msg="new websocket client"
Mar 03 21:04:33 volumio volumio[5463]: info: Connection to go-librespot Websocket established
Mar 03 21:04:36 volumio volumio[5463]: info: Getting Spotify volume
Mar 03 21:04:36 volumio volumio[5463]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Mar 03 21:04:36 volumio volumio[5463]: info: CoreCommandRouter::volumioGetState
Mar 03 21:04:36 volumio volumio[5463]: info: CorePlayQueue::getTrack 0
Mar 03 21:04:36 volumio volumio[5463]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Mar 03 21:04:36 volumio volumio[5463]: SPOTIFY: SPOTIFY VOLUME undefined
Mar 03 21:04:36 volumio volumio[5463]: SPOTIFY: VOLUMIO VOLUME 100
Mar 03 21:04:36 volumio volumio[5463]: info: Aligning Spotify Volume to Volumio Volume
Mar 03 21:04:36 volumio volumio[5463]: info: CoreCommandRouter::volumioGetState
Mar 03 21:04:36 volumio volumio[5463]: info: CorePlayQueue::getTrack 0
Mar 03 21:04:36 volumio volumio[5463]: info: Setting Spotify Volume from Volumio: 100
Mar 03 21:04:36 volumio go-librespot[6247]: time="2026-03-03T21:04:36-06:00" level=debug msg="obtained new client token: AACMU9npX+ePgtJgXuADGywGPR8swndAhupaUa+cGmU1/EBcE279uh4u50bWuFlrjFM0dDp7jJkAvmUGsBM9QzQLCsYwYuMomHf12tuESh1DhY80R06Jlo5kOZB9KQbf7Xeg0zhYU+8zDf00KJ6DsWOzeDKCftTh3T0USgxzSBeg4M0XCIu1eFL2mVKe6MNa/vRMCcMlC2FVLC591euMkta1T+GiyWq04s7xiv3asJJ4vTamQSaJEfWT4A=="
Mar 03 21:04:36 volumio go-librespot[6247]: time="2026-03-03T21:04:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Mar 03 21:04:36 volumio go-librespot[6247]: time="2026-03-03T21:04:36-06:00" level=debug msg="completed keyexchange"
Mar 03 21:04:36 volumio go-librespot[6247]: time="2026-03-03T21:04:36-06:00" level=debug msg="completed challenge"
Mar 03 21:04:36 volumio go-librespot[6247]: time="2026-03-03T21:04: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 "
Mar 03 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 03 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 03 21:04:36 volumio volumio[5463]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 03 21:04:36 volumio volumio[5463]: Error: socket hang up
Mar 03 21:04:36 volumio volumio[5463]:     at connResetException (node:internal/errors:720:14)
Mar 03 21:04:36 volumio volumio[5463]:     at Socket.socketOnEnd (node:_http_client:519:23)
Mar 03 21:04:36 volumio volumio[5463]:     at Socket.emit (node:events:526:35)
Mar 03 21:04:36 volumio volumio[5463]:     at endReadableNT (node:internal/streams/readable:1376:12)
Mar 03 21:04:36 volumio volumio[5463]:     at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Mar 03 21:04:36 volumio volumio[5463]:   code: 'ECONNRESET',
Mar 03 21:04:36 volumio volumio[5463]:   response: undefined
Mar 03 21:04:36 volumio volumio[5463]: }
Mar 03 21:04:36 volumio volumio[5463]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 03 21:04:37 volumio sudo[6282]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-03 21:03'
Mar 03 21:04:37 volumio sudo[6282]: 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"