Feb 12 20:22:35 volumio ntpd[1251]: CLOCK: time stepped by 2988.762888
Feb 12 20:22:35 volumio ntpd[1251]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Feb 12 20:22:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Feb 12 20:22:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:36 volumio go-librespot[2115]: go-librespot daemon starting...
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="app state loaded"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=info msg="zeroconf server listening on port 42643"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="obtained new client token: AADkxG/S63M28Q9fWWEd1YOiYp0ktrTtGax9c92JN9Te80PaRTcISYFVW1gY6cjgidK7HtMM/DAzE5olB9M4rVCak1+mHL0YiqK7K7oY1Qvn2SwVHqH5ZlvJSHBPiLnmhJuDOQdpfEltHt9O3bGqho7vUjRjf/2qAu8hzBm/1nRfJgUUJElrj7v76JXqtbntuON1964gjD4vLw4VkJusNWtLV8nl+XGf/jUvXQ2cgsq7ZX/oC54G3X4r"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=debug msg="completed challenge"
Feb 12 20:22:36 volumio go-librespot[2116]: time="2026-02-12T20:22:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:36 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:36 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:36 volumio volumio[1422]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 12 20:22:36 volumio volumio[1422]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Feb 12 20:22:36 volumio volumio[1422]: info: Volumio Network Manager: Network status updated: 2
Feb 12 20:22:36 volumio sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 12 20:22:36 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:36 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:36 volumio sudo[2147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 12 20:22:36 volumio sudo[2147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:36 volumio sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 12 20:22:36 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:36 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:37 volumio volumio[1422]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:37 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 12 20:22:37 volumio volumio[1422]: info: Received Get System Info
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 20:22:37 volumio volumio[1422]: info: Discovery: Getting this device information
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:37 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:37 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:37 volumio volumio[1422]: info: Listing playlists
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Feb 12 20:22:37 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 20:22:38 volumio volumio[1422]: info: Discovery: Started advertising with name: Volumio
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 20:22:39 volumio volumio[1422]: info: Received Get System Info
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 20:22:39 volumio volumio[1422]: info: Discovery: Getting this device information
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:39 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 20:22:39 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:39 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Feb 12 20:22:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:39 volumio go-librespot[2153]: go-librespot daemon starting...
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="app state loaded"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=info msg="zeroconf server listening on port 39935"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="obtained new client token: AACdbdcvK8ccab1amm0I3cEvNufMUM8oYfWzP721hrTZD0dvx0NYcqewBvdmVB7h/rnPfYJuCG1lHlgzMyUZzXA4/YcvkMRFfff6/p7bDFfxmo2nn9pDgSYEDW/MaRF2B26bEaxs+RAlJs6XbxgIqjiTY45G4i3mMtV3o+PIhEZDZHpdbo++Voufj10flaPFaegG0vcoiinNu3W8+z8U2EmHGx1vdXkOm0/edg6wOdlfeuRqByVs0zsqel0="
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=debug msg="completed challenge"
Feb 12 20:22:39 volumio go-librespot[2154]: time="2026-02-12T20:22:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Feb 12 20:22:39 volumio volumio[1422]: info: Discovery: adding c67b9a32-c6af-44f2-b408-438b8e84adf1
Feb 12 20:22:39 volumio volumio[1422]: info: Discovery: Found device Volumio
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:39 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:39 volumio volumio[1422]: info: Discovery: this is already registered, c67b9a32-c6af-44f2-b408-438b8e84adf1
Feb 12 20:22:39 volumio volumio[1422]: info: Discovery: Found device Volumio
Feb 12 20:22:39 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:39 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin multiroom to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 12 20:22:39 volumio volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:40 volumio volumio[1422]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 12 20:22:40 volumio volumio[1422]: info: MyVolumio login type: Token
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 12 20:22:40 volumio volumio[1422]: info: Streaming services startup
Feb 12 20:22:40 volumio volumio[1422]: info: Starting Streaming Daemon
Feb 12 20:22:40 volumio sudo[2164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 12 20:22:40 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:40 volumio volumio[1422]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 12 20:22:40 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 20:22:40 volumio volumio[1422]: info: Received Get System Info
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 20:22:40 volumio volumio[1422]: info: Discovery: Getting this device information
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:40 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:40 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 20:22:40 volumio volumio[1422]: error: Cannot start Volumio Streaming Daemon
Feb 12 20:22:40 volumio volumio[1422]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 12 20:22:40 volumio volumio[1422]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 12 20:22:41 volumio volumio[1422]: error: MyVolumio Custom Token format not valid, refreshing it
Feb 12 20:22:41 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Feb 12 20:22:41 volumio volumio[1422]: info: MyVolumio login type: Token
Feb 12 20:22:41 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 20:22:41 volumio volumio[1422]: info: MyVolumio login type: Token
Feb 12 20:22:41 volumio volumio[1422]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Feb 12 20:22:42 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 12 20:22:42 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:42 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Feb 12 20:22:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:42 volumio go-librespot[2172]: go-librespot daemon starting...
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="app state loaded"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=info msg="zeroconf server listening on port 44375"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="obtained new client token: AAC+fjWD7bQTN7pDK52gXXz+HnT/SnTHFEQbrc3G+nsGFtTqAbC4EqXli4iqXxnriYTmUfKxyOeF6uT3+huSudFKG8TG5YaK0TEGoD8oxcXwkLaPdX84+m5X1GR0nzK+k7RsqRXNfSu+6bpXbGV5LLYhgvjfnzEmoNqmww/KXXEGn/8BfY7BvBZJ41zJW+CChJdxGvNift5PLsxSz+4sRSz13r091IaZwLqRiU2BXEr2Ht4IIGLCRIPa1C4="
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=debug msg="completed challenge"
Feb 12 20:22:42 volumio volumio[1422]: info: MyVolumio token set successfully
Feb 12 20:22:42 volumio volumio[1422]: info: MYVOLUMIO: Adding device
Feb 12 20:22:42 volumio volumio[1422]: info: MYVOLUMIO: Evaluating Server
Feb 12 20:22:42 volumio go-librespot[2173]: time="2026-02-12T20:22:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:43 volumio sh[896]: timed out
Feb 12 20:22:43 volumio dhcpcd[909]: timed out
Feb 12 20:22:43 volumio sh[832]: ifup: failed to bring up eth0
Feb 12 20:22:43 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:43 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Feb 12 20:22:43 volumio volumio[1422]: info: MyVolumio status changed
Feb 12 20:22:43 volumio volumio[1422]: info: Streaming services startup
Feb 12 20:22:43 volumio volumio[1422]: info: Starting Streaming Daemon
Feb 12 20:22:43 volumio volumio[1422]: info: Removing browser output: myVolumio user plan is not superstar
Feb 12 20:22:43 volumio volumio[1422]: info: Removing audio output:
Feb 12 20:22:43 volumio volumio[1422]: info: Stoppping Tunnel 1
Feb 12 20:22:43 volumio sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 12 20:22:43 volumio sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:43 volumio sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Feb 12 20:22:43 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 20:22:43 volumio sudo[2201]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio volumio[1422]: error: Cannot start Volumio Streaming Daemon
Feb 12 20:22:43 volumio volumio[1422]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 12 20:22:43 volumio volumio[1422]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 12 20:22:43 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:43 volumio volumio[1422]: info: Remote SSH Stopped
Feb 12 20:22:43 volumio volumio[1422]: info: Setting Geolocation for MyVolumio to eu4
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:43 volumio volumio[1422]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 20:22:43 volumio volumio[1422]: info: Received Get System Info
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 20:22:43 volumio volumio[1422]: info: Discovery: Getting this device information
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:43 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:43 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 20:22:44 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Feb 12 20:22:44 volumio volumio[1422]: info: Updating MyVolumio device info
Feb 12 20:22:44 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:44 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:44 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:44 volumio volumio[1422]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 12 20:22:45 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:45 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 12 20:22:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 12 20:22:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 12 20:22:45 volumio sudo[2147]: pam_unix(sudo:session): session closed for user root
Feb 12 20:22:45 volumio volumio[1422]: info: Upmpdcli Daemon Started
Feb 12 20:22:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Feb 12 20:22:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:46 volumio go-librespot[2227]: go-librespot daemon starting...
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="app state loaded"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=info msg="zeroconf server listening on port 35319"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="obtained new client token: AAAJLATuagnwhs5Ig2b4D55NMFi4ckn1q31tIm0J6CcQ7mHVgYjnXYdjtfEd9uD4swnIROgIan7IElzCEhuwnCV6lcIxAUW8Aey7Ulkasib9+gqdtQqBjyGjwUJ/FLVfxWR8QOJGzJTdZgcavYV5GS1LaCUXezdyBAKUynbkw2Sy1Z6waJNSMFQGxtg29dz9ST5IIZkfB51X1HwxthQERZdolbxwLN+GIO8usIB2ZzVzVFfBVO3dJaiG"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=debug msg="completed challenge"
Feb 12 20:22:46 volumio go-librespot[2228]: time="2026-02-12T20:22:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:47 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Feb 12 20:22:47 volumio volumio[1422]: info: MYVOLUMIO: Adding device
Feb 12 20:22:47 volumio volumio[1422]: info: MYVOLUMIO: Evaluating Server
Feb 12 20:22:48 volumio volumio[1422]: info: Setting Geolocation for MyVolumio to eu4
Feb 12 20:22:48 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:48 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:48 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:48 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:48 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:48 volumio volumio[1422]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 12 20:22:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Feb 12 20:22:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:49 volumio volumio[1422]: info: Updating MyVolumio device info
Feb 12 20:22:49 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:49 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:49 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:49 volumio go-librespot[2268]: go-librespot daemon starting...
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="app state loaded"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=info msg="zeroconf server listening on port 44679"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="obtained new client token: AACBCZtkXd36RFsgFHoTI28gFepyvtKjzVX9Obs1JdJYw//McdghEHwGRXEpzGNLcthdbqWibg7S8UXCDlNv+wuHXCThHhUzD4hFkjMBTCyABirqrJF6/NNPXHJsl9AUYLMIxguMjigOHzEq5apYIfN/ncv9d3PxnI+FVbKgbz8bffRhxsVFuIMVRgH92sugHod+rYjURyOeCCTe0t8R4rbskR+TLj812B5rbVGfscFkSsCmZdlVuOfw+xM="
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=debug msg="completed challenge"
Feb 12 20:22:49 volumio go-librespot[2269]: time="2026-02-12T20:22:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:49 volumio volumio[1422]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 12 20:22:51 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:51 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Feb 12 20:22:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:52 volumio go-librespot[2277]: go-librespot daemon starting...
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="app state loaded"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=info msg="zeroconf server listening on port 46475"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="obtained new client token: AAAcMba5epg2yUbOhWhcI3oVvKHafgFUBHc/owiiGlJQY1FILQGwQrV+Srw5jZwz0tG8ZB3veFLTV80pBtj0szvWMmH2f6jcuR+Ovl+f3tJ/l6x9PDRRIe/kB86/wqslWFPSU+NK3NgS0tw6ydbJz1jlIfDMLqOIShFOFEm2V1tWET/h07KNGH6OeVdp2AJICimUOA5aoNj2pHuxMIRxq+stj1AgF0tvNG53X2bBA2P5RBJJryB79qrECXY="
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=debug msg="completed challenge"
Feb 12 20:22:52 volumio go-librespot[2278]: time="2026-02-12T20:22:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:53 volumio volumio[1422]: info: CoreCommandRouter::volumioGetQueue
Feb 12 20:22:53 volumio volumio[1422]: info: CoreStateMachine::getQueue
Feb 12 20:22:53 volumio volumio[1422]: info: CorePlayQueue::getQueue
Feb 12 20:22:54 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:54 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:54 volumio upmpdcli[2285]: writing RSA key
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 20:22:55 volumio volumio[1422]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 12 20:22:55 volumio volumio[1422]: info: Received Get System Version
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 20:22:55 volumio volumio[1422]: info: Received Get System Info
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 20:22:55 volumio volumio[1422]: info: Discovery: Getting this device information
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:55 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 20:22:55 volumio volumio[1422]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Feb 12 20:22:55 volumio volumio[1422]: info: CoreCommandRouter::volumioGetState
Feb 12 20:22:55 volumio volumio[1422]: info: CorePlayQueue::getTrack 0
Feb 12 20:22:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Feb 12 20:22:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:56 volumio go-librespot[2299]: go-librespot daemon starting...
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="app state loaded"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=info msg="zeroconf server listening on port 35423"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="obtained new client token: AABo2+YkC1RrxgkVda7UDO0Muna7vDOt0ptEyegtTSDBGdNZvtxr7cZWD/E+1T4lq7HLCxMsqYw/ZbRby7Og1kh6XvN8Rl+8jPqG6LtYOYnUsPrWoHLRXtszhmhDTwcTwfL6U6o/R/UtXbXlaBUE5NG1ReRPlIZOXwvbCSCi6bCad5wSLr5D0YHvPbn6ml7PBPbnkKfR/1AyywuFZWL9ZNytIjumfRaJ+e3v0AiWx5AVsHuQn5+2gktz"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=debug msg="completed challenge"
Feb 12 20:22:56 volumio go-librespot[2300]: time="2026-02-12T20:22:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:22:57 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:22:57 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:22:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Feb 12 20:22:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:22:59 volumio go-librespot[2321]: go-librespot daemon starting...
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="app state loaded"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:22:59 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 20:22:59 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:22:59 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=info msg="zeroconf server listening on port 34587"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="obtained new client token: AACqOkFRXdG3eDygsGvv6kkK0XOO+KrtOh1+jDk9Sj89rcyTdJD2+FOmR1tSiECOk13yz4dUJs9P0QStvC86R+/MMHx+9SZIPhXc+rMb/kJu5H8FkwTbLzpZb0O0zgDGLdNh76ebpt5Rbk8y7MYxoVqzCkaCO1n5HZg9i+XOdcl67ojaxx0kv1w9t0m5blOOIkzkOEdJoFPxOSFuWHE5/dKFNnrFDhujRqv5do+CgTldWu0mx4TmE36EBYg="
Feb 12 20:22:59 volumio volumio-remote-updater[1015]: Test mode disabled
Feb 12 20:22:59 volumio volumio-remote-updater[1015]: Alpha mode disabled
Feb 12 20:22:59 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled
Feb 12 20:22:59 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Feb 12 20:22:59 volumio volumio[1422]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Feb 12 20:22:59 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="completed keyexchange"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=debug msg="completed challenge"
Feb 12 20:22:59 volumio go-librespot[2322]: time="2026-02-12T20:22:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:22:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:22:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:23:00 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:23:00 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 20:23:00 volumio volumio[1422]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 12 20:23:00 volumio volumio[1422]: info: Completed starting MyVolumio Plugin
Feb 12 20:23:00 volumio volumio[1422]: [Metrics] CommandRouter: 34s 713.16ms
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::Close All Modals sent
Feb 12 20:23:00 volumio volumio[1422]: info: CoreCommandRouter::Close All Modals sent
Feb 12 20:23:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 12 20:23:01 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 12 20:23:01 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 12 20:23:01 volumio volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 12 20:23:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 12 20:23:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 12 20:23:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Feb 12 20:23:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:02 volumio go-librespot[2363]: go-librespot daemon starting...
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="app state loaded"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=info msg="zeroconf server listening on port 44839"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="obtained new client token: AABWSbrogwn4y7Q/BtywO14c+FpviVaXBcMmX6IUTT5edU/smJZ860NuDtRRn/4eC358n6m8F8DtUCNmHKzv796a2fEbO1JVMMUQNN8gBgJEguYg3FwLKYDNorGhQpZ7rewAAiRhy8Imwu40/a+lcgO33McrhCS5Tg1cAw6nNyeAJ6sb6KUNDNh0VfocuGKFaZNLxvbAIqYMhUGkYiOwf/VEpCV6iSxNUbo2mu/b+4nAy+RtY7lAE5n9g44="
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="completed keyexchange"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=debug msg="completed challenge"
Feb 12 20:23:02 volumio go-librespot[2364]: time="2026-02-12T20:23:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:23:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:23:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:23:03 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:23:03 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:23:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Feb 12 20:23:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:06 volumio go-librespot[2371]: go-librespot daemon starting...
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="app state loaded"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=info msg="zeroconf server listening on port 39429"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="obtained new client token: AADJ+PbQ//MsQ8hBhqVQxEpzaClF4GYw+naAyXglP7k79gAV/qZIO9abfIxRozRV0OmH6Qalr3vkzkX8hDG073LA3336E1Orl4o9KX/4HGzztF7liasMNV5+t+8txG005fymTqZkdBsGTONeq4zJSXpO7nEv0ibtni4o1o/ELCeycjGq5AH9vUu6CE8Ch04tAlzzw6QCo56pl/ao+5N+UeAMYowtaveETKeW1hksoCHC6smhaP7ptM82"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="completed keyexchange"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=debug msg="completed challenge"
Feb 12 20:23:06 volumio go-librespot[2372]: time="2026-02-12T20:23:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:23:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:23:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:23:06 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:23:06 volumio volumio[1422]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:23:07 volumio volumio[1422]: info: BOOT COMPLETED
Feb 12 20:23:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Feb 12 20:23:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 12 20:23:09 volumio go-librespot[2393]: go-librespot daemon starting...
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=info msg="running go-librespot 0.4.0"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="app state loaded"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 12 20:23:09 volumio volumio[1422]: info: Initializing connection to go-librespot Websocket
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="new websocket client"
Feb 12 20:23:09 volumio volumio[1422]: info: Connection to go-librespot Websocket established
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=info msg="zeroconf server listening on port 39865"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="obtained new client token: AAD/E8wXWxCbDrYBCnDjSPeSsglT061LlMlAs1RXI4AvLT9QKyQToOLs4CJeosMxRP2qdg6nMtTQ6p8SXfLMpqciO2MRQ1LvaFwEimRsh1Pk5SMoqZ2uaNPKI0MJK7whIDwkSqfgdKsMhqxt7xung5/rQemOhZNTzYh4PLLXtssHH8p8FKQsR84czXVgCziVZ+6OzsKrzeK8GbAc7aHVck/kV+LB1LsgLevoinKwFGhAfoe8sRyjt9RVeZI="
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="completed keyexchange"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=debug msg="completed challenge"
Feb 12 20:23:09 volumio go-librespot[2394]: time="2026-02-12T20:23:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Feb 12 20:23:09 volumio volumio[1422]: info: Connection to go-librespot Websocket closed
Feb 12 20:23:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 12 20:23:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 12 20:23:12 volumio volumio[1422]: info: Getting Spotify volume
Feb 12 20:23:12 volumio volumio[1422]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 20:23:12 volumio volumio[1422]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 12 20:23:12 volumio volumio[1422]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 12 20:23:12 volumio volumio[1422]: errno: -111,
Feb 12 20:23:12 volumio volumio[1422]: code: 'ECONNREFUSED',
Feb 12 20:23:12 volumio volumio[1422]: syscall: 'connect',
Feb 12 20:23:12 volumio volumio[1422]: address: '127.0.0.1',
Feb 12 20:23:12 volumio volumio[1422]: port: 9879,
Feb 12 20:23:12 volumio volumio[1422]: response: undefined
Feb 12 20:23:12 volumio volumio[1422]: }
Feb 12 20:23:12 volumio volumio[1422]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 20:23:12 volumio sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:22'
Feb 12 20:23:12 volumio sudo[2423]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026"
VOLUMIO_VERSION="4.096"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"