Aug 25 20:12:42 volumioth volumio[1144]: info: Received OAUTH Data Aug 25 20:12:42 volumioth volumio[1144]: info: Executing Spotify Oauth Login Aug 25 20:12:42 volumioth volumio[1144]: info: Saving Spotify Refresh Token Aug 25 20:12:42 volumioth volumio[1144]: info: New Spotify access tokenBQAlYVd-o-... Aug 25 20:12:42 volumioth volumio[1144]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 25 20:12:42 volumioth sudo[8906]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 20:12:42 volumioth sudo[8906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 20:12:42 volumioth volumio[1144]: SPOTIFY: User informations: {"account_id":"hcSEHlSHFV","country":"DE","display_name":"Tobias","email":"meisterobiwahn@googlemail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1124775707"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/1124775707","id":"1124775707","images":[],"product":"premium","type":"user","uri":"spotify:user:1124775707"} Aug 25 20:12:42 volumioth volumio[1144]: info: Creating Spotify config file Aug 25 20:12:42 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 20:12:43 volumioth sudo[8906]: pam_unix(sudo:session): session closed for user root Aug 25 20:12:43 volumioth sudo[8908]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 20:12:43 volumioth sudo[8908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 20:12:43 volumioth volumio[1144]: info: Spotify config file written Aug 25 20:12:43 volumioth sudo[8908]: pam_unix(sudo:session): session closed for user root Aug 25 20:12:43 volumioth sudo[8913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 25 20:12:43 volumioth sudo[8913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 20:12:43 volumioth systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 25 20:12:43 volumioth systemd[1]: go-librespot-daemon.service: Killing process 8792 (go-librespot) with signal SIGKILL. Aug 25 20:12:43 volumioth systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 25 20:12:43 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:43 volumioth volumio[1144]: verbose: New Socket.io Connection to 192.168.178.42 from 192.168.178.56 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 25 20:12:43 volumioth volumio[1144]: info: Connection to go-librespot Websocket closed Aug 25 20:12:43 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:43 volumioth go-librespot[8915]: go-librespot daemon starting... Aug 25 20:12:43 volumioth sudo[8913]: pam_unix(sudo:session): session closed for user root Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 20:12:43 volumioth volumio[1144]: info: New Spotify access tokenBQDUVAleRj... Aug 25 20:12:43 volumioth volumio[1144]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="app state loaded" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="stored credentials not found" Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:12:43 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 20:12:43 volumioth volumio[1144]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 25 20:12:43 volumioth volumio[1144]: info: Received Get System Info Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:12:43 volumioth volumio[1144]: info: Discovery: Getting this device information Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:12:43 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:12:43 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:12:43 volumioth volumio[1144]: info: Listing playlists Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 20:12:43 volumioth volumio[1144]: SPOTIFY: User informations: {"account_id":"hcSEHlSHFV","country":"DE","display_name":"Tobias","email":"meisterobiwahn@googlemail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1124775707"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/1124775707","id":"1124775707","images":[],"product":"premium","type":"user","uri":"spotify:user:1124775707"} Aug 25 20:12:43 volumioth volumio[1144]: info: Spotify Successfully logged in Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 20:12:43 volumioth volumio[1144]: info: [1787681563368] CoreMusicLibrary::Adding element Spotify Aug 25 20:12:43 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 20:12:43 volumioth volumio[1144]: Cannot find translation for source Jellyfin Aug 25 20:12:43 volumioth volumio[1144]: Cannot find translation for source Podcast Aug 25 20:12:43 volumioth volumio[1144]: Cannot find translation for source Volusonic Aug 25 20:12:43 volumioth volumio[1144]: Cannot find translation for source C-Media USB Aug 25 20:12:43 volumioth volumio[1144]: Cannot find translation for source Spotify Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=info msg="zeroconf server listening on port 40927" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="obtained new client token: AAHORNUEvUz1luXgyIMbtDA9gGd2JGvI9MzOVV/Axd2kqVjCwiyDGL9vDGKxregoIpUDrVi4GbpIeVkBPAXjVCltVTLMvcVqgojQoXan+XbqS/RePTiLifyQsx6cEb0ZirJdm9JrhzOUz5l+QqAWHDGCzdifV8ybjqsF6Dyvs/Fu1g8Z4FEspOS6YFcQBFS0UQcExFwXbxg3Vo35RCYfB5zen9eTHU477KYVKaR6pOV+27eFZ/FIwA==" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02: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" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="completed keyexchange" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=debug msg="completed challenge" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:12:43 volumioth go-librespot[8916]: time="2026-08-25T20:12:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:12:43 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:12:43 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 20:12:44 volumioth volumio[1144]: info: Received Get System Info Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:12:44 volumioth volumio[1144]: info: Discovery: Getting this device information Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:12:44 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:12:44 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:12:45 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 20:12:45 volumioth volumio[1144]: info: Received Get System Info Aug 25 20:12:45 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:12:45 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:12:45 volumioth volumio[1144]: info: Discovery: Getting this device information Aug 25 20:12:45 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:12:45 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:12:45 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:12:46 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:46 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:46 volumioth volumio[1144]: info: go-librespot daemon successfully initialized Aug 25 20:12:47 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 25 20:12:47 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:47 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:47 volumioth go-librespot[8927]: go-librespot daemon starting... Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="app state loaded" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="stored credentials not found" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=info msg="zeroconf server listening on port 46173" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="obtained new client token: AAF8RbR3umwc7rZ+9LKz6vggBJUGsieawaAAwXNlY8TL8IvD+JJGEIv8lsg/gHw1IYur+eidIVEJF6NrKRlq4g3NH2apUdAu2eo92HxMSnQtJubf5qztXJPpjxat/SicXDQ0T08wsKF3vMPq4cZeAH7EBtXUeow7HRlysiS2rVw8aUSORs410fYEBOoh/nyhd/TZiWkJueK9TSLclcng4JVXFmefUqujMyhkibvqsjscDsQPOSEYrA==" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02: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" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="completed keyexchange" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=debug msg="completed challenge" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:12:47 volumioth go-librespot[8928]: time="2026-08-25T20:12:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:12:47 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:12:47 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:12:49 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:49 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:49 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:49 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:50 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 25 20:12:50 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:50 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:50 volumioth go-librespot[8951]: go-librespot daemon starting... Aug 25 20:12:50 volumioth go-librespot[8952]: time="2026-08-25T20:12:50+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:12:50 volumioth go-librespot[8952]: time="2026-08-25T20:12:50+02:00" level=debug msg="app state loaded" Aug 25 20:12:50 volumioth go-librespot[8952]: time="2026-08-25T20:12:50+02:00" level=debug msg="stored credentials not found" Aug 25 20:12:50 volumioth go-librespot[8952]: time="2026-08-25T20:12:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=info msg="zeroconf server listening on port 40779" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="obtained new client token: AAENZ6Q0NQB/1hkwyKNRqAra5BhjVceLEqypIz/GSqt6RFS20ort8DHvgDd4FMG7TyPoBPE19oac0r107FJUU953FuWZZzGM8h6BG1jUigkiwTOBSigJMOgjeFDAo12gCWfNKKfV4jQM4BLJxzPdwKwb6C9PTZSSD2x96P0fneeERbVGKvn7AHD8puGPUt08f3IFBcVK18+NmMAzO3zjokOC1V4/n13jbpiu4FJopzzpA8xKajo=" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02: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" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="completed keyexchange" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=debug msg="completed challenge" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:12:51 volumioth go-librespot[8952]: time="2026-08-25T20:12:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:12:51 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:12:51 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:12:52 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:52 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:53 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 20:12:54 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 25 20:12:54 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:54 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:54 volumioth go-librespot[8961]: go-librespot daemon starting... Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="app state loaded" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="stored credentials not found" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=info msg="zeroconf server listening on port 45767" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="obtained new client token: AAFNnH4JRqK07IRmrEhQkFzup0PDHQY4fjgsTOoebUC7uvzOuW7qWESlSL4WLEwz4uQXEF7Cfu4UVmILQlWYLyGHJX7RNXWgBZEhQYTjfGYMaj6mmQLN8jms2NCylEEr1G6EdSBkpZKmKlSXEtvBkc6LJwsXaWV1JSZda5lyrvp4m8erwDvYCZST66uJaGPDFFLUw3q5l5bsIebye2DXa41T/+08yIxyNJgDh70/LsltFpXMkq68Ww==" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="completed keyexchange" Aug 25 20:12:54 volumioth go-librespot[8962]: time="2026-08-25T20:12:54+02:00" level=debug msg="completed challenge" Aug 25 20:12:55 volumioth go-librespot[8962]: time="2026-08-25T20:12:55+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:12:55 volumioth go-librespot[8962]: time="2026-08-25T20:12:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:12:55 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:12:55 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:12:55 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:55 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:58 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:12:58 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:12:58 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 25 20:12:58 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:58 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:12:58 volumioth go-librespot[8974]: go-librespot daemon starting... Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="app state loaded" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="stored credentials not found" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=info msg="zeroconf server listening on port 44173" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="obtained new client token: AAFfgJ7eXtngSMBtLE4j2GlAKfu+LRGKSKaYnqMtJL+dHZ4VCtA0tWnpE7i+HTOKIfZnAR+oF/JLCwneFCDDkqGgep6eH+lAzGQT5Btup2y3XExLpwlMrxDzhpCphIzBF38BrVNVFmRmwEj4we0taL3WqHKD6656yrIiymSXL0zX648MCtXHWBFpQVwuUWE02tLRTQfBfJU8EgTRbNHsva3a3UXIfUa0FpJhF0BC/qd9YH5LS00avw==" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="completed keyexchange" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=debug msg="completed challenge" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:12:58 volumioth go-librespot[8975]: time="2026-08-25T20:12:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:12:58 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:12:58 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 20:13:01 volumioth volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 20:13:01 volumioth volumio[1144]: info: Received Get System Version Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 20:13:01 volumioth volumio[1144]: info: Received Get System Info Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 20:13:01 volumioth volumio[1144]: info: Discovery: Getting this device information Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:13:01 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:13:01 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 20:13:01 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:01 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:02 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 25 20:13:02 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:02 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:02 volumioth go-librespot[8998]: go-librespot daemon starting... Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="app state loaded" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=info msg="zeroconf server listening on port 37369" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="obtained new client token: AAEtnTq88/l1m6hjvTe1VISQHtB/U9AQevFNgIKBOHJB3wq1MHALSzdBzzfuqn3KvckTPrX+n4cPAx4oWp2dHnTyEALgammteuX+Npcm9G4qitirgMzZkjVC0ssvvCyVhNqtPMzjmY+q3Ux3V60yawlasQw2trBkQyEn4PjiUdGkbGvW/NuFuPFsU8GxUDFnbEwocivZHSFcKUSfBYCP7Ob1Yc8ApA6b6WK/43NZFP3xXhnyRNNgMQ==" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=debug msg="completed challenge" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:02 volumioth go-librespot[8999]: time="2026-08-25T20:13:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:02 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:02 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:03 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:13:03 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:13:04 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:04 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:05 volumioth volumio[1144]: info: Executing endpoint metavolumio Aug 25 20:13:05 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 20:13:05 volumioth volumio[1144]: info: Executing endpoint metavolumio Aug 25 20:13:05 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 20:13:05 volumioth volumio[1144]: info: Executing endpoint metavolumio Aug 25 20:13:05 volumioth volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 20:13:05 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 25 20:13:05 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:05 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:05 volumioth go-librespot[9009]: go-librespot daemon starting... Aug 25 20:13:05 volumioth go-librespot[9010]: time="2026-08-25T20:13:05+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:05 volumioth go-librespot[9010]: time="2026-08-25T20:13:05+02:00" level=debug msg="app state loaded" Aug 25 20:13:05 volumioth go-librespot[9010]: time="2026-08-25T20:13:05+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:05 volumioth go-librespot[9010]: time="2026-08-25T20:13:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=info msg="zeroconf server listening on port 40397" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="obtained new client token: AAEvcTBX/Jg2O6wOKuA7mDroL9LgtpKtlhOuBhTo2aNysxLji79qqotdMirNuLTR5YClA+NQP6l2sCUwk7g+PcBMieR2+x57hGx4pxZs0tjCx2qsk6GNuCxcnvyEK7DzFyBdUOBuaVh79rjv5yUpLNH9CLDdgPE564vFuQvZUUQmKOkQ0zTcpUsmScX7A/SrixrT4/X0zpcNK/t8Gw9NoZXFz4ylh3isHK1SpbJ+qDA88XnJY8o=" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=debug msg="completed challenge" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:06 volumioth go-librespot[9010]: time="2026-08-25T20:13:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:06 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:06 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:07 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:07 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:09 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 25 20:13:09 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:09 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:09 volumioth go-librespot[9020]: go-librespot daemon starting... Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="app state loaded" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=info msg="zeroconf server listening on port 37909" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="obtained new client token: AAFy1MKJOW3Q8Axqz09w6V0OoCRGypwenVy0xUefFRLfg/dgb8sS6oHlYJVmBrzmxpc0smLkUraIPC58uWtDxUCk3hgbZEg8jTe2IafFjesYs+t2dXS/p06Mle/p5b2g9jYoNVXaaKbdDbtZW8jT6mNiDtkx5xn2O1npZIAWseTlws0yt6P04+FLTyIrXNoJKqVUts7kYJSRcpGetyO8A0HLHssPDugnD8Lkf5Myz3CLAeGEgsEtIA==" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=debug msg="completed challenge" Aug 25 20:13:09 volumioth go-librespot[9021]: time="2026-08-25T20:13:09+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:10 volumioth go-librespot[9021]: time="2026-08-25T20:13:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:10 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:10 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:10 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:10 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:13 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:13 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:13 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 25 20:13:13 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:13 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:13 volumioth go-librespot[9045]: go-librespot daemon starting... Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="app state loaded" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=info msg="zeroconf server listening on port 40619" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="obtained new client token: AAG0RbeqOyE5OtPS5kaZBjft+1jGp3eDZVCyAQ+GUan/Sch4HvklnECUXGoeZzEi+f8dOEtwEyRX2glp0wKVbVNp2sigX4prxn4GXSoDmNGpbTBMG1kkGfYhfZBQXEAIp0a/S9q6VB5pIpWAs02fmyk3FQC0b20V9tqkC2WJbBP8dRDcRS/EsaUgv0Dq0x5ckQzVku/zOuHdNNg4zRwIS3KbbR72u49oTRPsYVWd1sp6Xk1khhaCtA==" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=debug msg="completed challenge" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:13 volumioth go-librespot[9046]: time="2026-08-25T20:13:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:13 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:13 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:16 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:16 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:17 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 25 20:13:17 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:17 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:17 volumioth go-librespot[9056]: go-librespot daemon starting... Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="app state loaded" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=info msg="zeroconf server listening on port 33719" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="obtained new client token: AAHTuc5QQd0sT92yroUNxP+00zvlXmJTKmxke8d0zRb/WysZwjAZ4l6qZK6cPJFrK/ZcM2FPdzuSI8wXfXjoVj3zg351aZWop0sHBfF13h8Bw2WZRmdsARzgkO6ShK3ac5TnAxmVL/KdzM8h9STbYRiFSVVGalOI5AwVRUXkpmzZ31AfRPVqthqYO09v7MhHkQyIccp+jCZXDH8yyL0XAMXRriOLctTIpp+LMycCG1sPp43sXnB4tw==" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=debug msg="completed challenge" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:17 volumioth go-librespot[9057]: time="2026-08-25T20:13:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:17 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:17 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:19 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:19 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:20 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 25 20:13:20 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:20 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:20 volumioth go-librespot[9080]: go-librespot daemon starting... Aug 25 20:13:20 volumioth go-librespot[9081]: time="2026-08-25T20:13:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:20 volumioth go-librespot[9081]: time="2026-08-25T20:13:20+02:00" level=debug msg="app state loaded" Aug 25 20:13:20 volumioth go-librespot[9081]: time="2026-08-25T20:13:20+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:20 volumioth go-librespot[9081]: time="2026-08-25T20:13:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=info msg="zeroconf server listening on port 36311" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="obtained new client token: AAHuY+xaybj+BkOfgW5OY+NyKnhJj5WLvr3O0RPwDxvl1mLaDmgEVkrzluiu5B57mGc8EU6tKBdBdQHhfap6ImhsoVDc7tccNDm//0AwFs7+Ul4enowZ4NaQ8OZfGeAn1ba1dfQobTydl4gz5mtv+RyrbA65eLLFshVfQXtekRrfx1kTDSKjjrN+ORASCAhqZ4vQpWt9cnjTOAop1ihTjQ7tcLkalOOdCPCOkcFIzznLcLJpQLA=" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=debug msg="completed challenge" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:21 volumioth go-librespot[9081]: time="2026-08-25T20:13:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:21 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:21 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:22 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:22 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:24 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 25 20:13:24 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:24 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:24 volumioth go-librespot[9091]: go-librespot daemon starting... Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="app state loaded" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=info msg="zeroconf server listening on port 37043" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="obtained new client token: AAEHmO8+umMJidkcAmGL8nxIz/Musq3o7PsTNiZf/J+n9HdEYTrZU2TfUzove737ruWYs2FFbbyJc65d7TsFaSFqVNy/iiw6hh4LeWwo7DAab0cf0EC/BEOgoeVv/7UKF7aNB6HSg0CGPL97sc5EAGTNNYNJveD7NrdZfL/n36TZrI/5/4YCpdAm2Px96ObRlhRV+VvpXtmdMojAdqq3vS1kfgvxbm6XVk+EFN1veHN4uvvaOjOk4Q==" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02: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" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:24 volumioth go-librespot[9092]: time="2026-08-25T20:13:24+02:00" level=debug msg="completed challenge" Aug 25 20:13:25 volumioth go-librespot[9092]: time="2026-08-25T20:13:25+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:25 volumioth go-librespot[9092]: time="2026-08-25T20:13:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:25 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:25 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:25 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:25 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:28 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 25 20:13:28 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:28 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:28 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:28 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:28 volumioth go-librespot[9103]: go-librespot daemon starting... Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="app state loaded" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=info msg="zeroconf server listening on port 32825" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="obtained new client token: AAHY1khDt73O9H+aydiaDe0Dx0060A2GeL5qoh0aP8YGtDiA9ti9d+H+f6WRyC3CTPCIiNzS0y7aITKbMhZvxcwz3QymIpXGSCYEVnO67DStk4IiTvwelrZUZFMXbRCFqtgBUYooom6FeKfW67WfUQqACU2rVQ98y+8uXsap9roYwTPY7y7MoW6XyyAFLOpVxV9b+hlyjmzpgik2oELTQ8SN6UDwzmZ42JlAlKVV2EsyVjgccELPQA==" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=debug msg="completed challenge" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:28 volumioth go-librespot[9104]: time="2026-08-25T20:13:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:28 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:28 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:31 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:31 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:32 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 25 20:13:32 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:32 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:32 volumioth go-librespot[9129]: go-librespot daemon starting... Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="app state loaded" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=info msg="zeroconf server listening on port 33289" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="obtained new client token: AAF0bRHSC9pdSGmUE6I6rSTIooTBcvQ6xNRY86D4iwOaumm03QEbdTJdKF0UVvaGDLYN+Xy0sq9cg0hs/IZNthoC/T45bRoogjeQfl2hoO+7hBML7S3qInQVquJmpef/pVHIPyyU4Pg72qAnZ/LVXQ6S7CaREXGgTNsRAyBmDbVp7GlKBpg65LBFSfOdDaIM23IixiW4QJSjRQXno8BhAaIyqvHsigDD83yBv280QOZ6UhrbQIJFrw==" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=debug msg="completed challenge" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:32 volumioth go-librespot[9130]: time="2026-08-25T20:13:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:32 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:32 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:34 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:34 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:35 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 25 20:13:35 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:35 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:35 volumioth go-librespot[9139]: go-librespot daemon starting... Aug 25 20:13:35 volumioth go-librespot[9140]: time="2026-08-25T20:13:35+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:35 volumioth go-librespot[9140]: time="2026-08-25T20:13:35+02:00" level=debug msg="app state loaded" Aug 25 20:13:35 volumioth go-librespot[9140]: time="2026-08-25T20:13:35+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:35 volumioth go-librespot[9140]: time="2026-08-25T20:13:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=info msg="zeroconf server listening on port 42337" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="obtained new client token: AAF+4JB8fAVp4pfnKnQXDQV1h44dmEVNKNC82W2QbhAVXbE20h2FAb8e7FCYxvLJEbA/Fef2Jr8wjrRk25HT358FcRvG2orSuGRb7iHeoDlXOe9S21E9xZnexC/4APD0HKaZNDsXVrE8A4VU4BP0zcHV+BsUc6zDK91yiE+Mh+OnSWGnepLAXasB1X++jc2K3T+8lCIw/stwODucka4LWZtC6KM1oGPHIL69rA+XeXo0LxNWWWg=" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=debug msg="completed challenge" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:36 volumioth go-librespot[9140]: time="2026-08-25T20:13:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:36 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:36 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:37 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:37 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:39 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 25 20:13:39 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:39 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:39 volumioth go-librespot[9150]: go-librespot daemon starting... Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="app state loaded" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=info msg="zeroconf server listening on port 33589" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="obtained new client token: AAEkZPKP1k2KJu5+EzEEvRmSmjNg3Y2l8LMdp8t3eBvCgnatBGniXiuCUb7Z7i/WDAHZo4BWMNVwTZsfvJlw/ar4n3aotPeHJ0vmZ80FNTBZbbxw8SdUOft2+gxau6R9KpNQpdcZuIO5zxHAqN1Z4fTQNEkZ46Qvdzuph7Gwpza5nGKsCPis0TWB1COa6r7zwcIWBrF9/lUrX8cZC2sSZq1cC0SfptXqgm9woogCCrGys/2WjpMPtg==" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=debug msg="completed challenge" Aug 25 20:13:39 volumioth go-librespot[9151]: time="2026-08-25T20:13:39+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:40 volumioth go-librespot[9151]: time="2026-08-25T20:13:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:40 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:40 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:40 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:40 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:43 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 25 20:13:43 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:43 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:43 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:43 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:43 volumioth go-librespot[9174]: go-librespot daemon starting... Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="app state loaded" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=info msg="zeroconf server listening on port 43115" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="obtained new client token: AAHA5Iw6O9XoiLAVSqGXaqokOTn9NUw1o70BdKqXrvokTbAWD+WEbd1XQegD/LL47fkiazU3FnSTf8GYv1TXwZSnx5Nak5hoVhth2BuMHQOjKQg4YsIkSTNWIowsGbK2oLns0msM9ZI2DUAv3RwDL1ZMZQYE2nI1WLHEwQCbdw+BuoZfTcqAZTO1PgGkuLpZo65zXfs3kP++uxQ4lAK1xBzcxa7jagtdOEa+Ou5QJJx8H+JOxxUrOA==" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=debug msg="completed challenge" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:43 volumioth go-librespot[9175]: time="2026-08-25T20:13:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:43 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:43 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:46 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:46 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:47 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 25 20:13:47 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:47 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:47 volumioth go-librespot[9184]: go-librespot daemon starting... Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="app state loaded" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=info msg="zeroconf server listening on port 44315" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="obtained new client token: AAHwbCViZBIVjpO120NQMJWSGLq7bVNU2keA2CXtBcjUWwr1BIb4PsOHaZV1pIWG20JX4MdjmiUlaMsqKgP76mqXNP6QBNT+ZE7qTSvyO0E9hI5E3iU5EXla2Zc89vjggbXnDV7gjA1ijUB9OviNxXFogwjAM+Uj4EEKAWEM2xr/GhmXATmBJFI8X9A1fsCowMUtD+etgPUJTwnkT0m4ftvx8fXXvNmNizWIhfXW0IZ0A9XbzgAz7A==" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=debug msg="completed challenge" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:47 volumioth go-librespot[9185]: time="2026-08-25T20:13:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:47 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:47 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:49 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:49 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:50 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 25 20:13:50 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:50 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:50 volumioth go-librespot[9208]: go-librespot daemon starting... Aug 25 20:13:50 volumioth go-librespot[9209]: time="2026-08-25T20:13:50+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:50 volumioth go-librespot[9209]: time="2026-08-25T20:13:50+02:00" level=debug msg="app state loaded" Aug 25 20:13:50 volumioth go-librespot[9209]: time="2026-08-25T20:13:50+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:50 volumioth go-librespot[9209]: time="2026-08-25T20:13:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=info msg="zeroconf server listening on port 38503" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="obtained new client token: AAFKlrtf/mkSK11Zs5yLJCBglSgm02Aqb11AoRzNxv7TPTEVAuVwjjbE4tx4WXsngHGGCcA5K9KC9L94suC4pbXSOO/qT5LJ6eqYcz1Baossb/UJ1+64hLi8LEmtGBwoAWStfy3XExDW+fUu6xKnLToxbcfKdjN2D08MoOb4dgoY/PaA8inKgAj74lcqtH5Sq1p04j+MizV+kReBER7rX6X32MPanI2RZdoySRHDOUZ8+LjPqJw=" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.178.42:51984->34.158.1.133:4070: read: connection reset by peer" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=debug msg="completed challenge" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:51 volumioth go-librespot[9209]: time="2026-08-25T20:13:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:51 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:51 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:52 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:52 volumioth volumio[1144]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:53 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetQueue Aug 25 20:13:53 volumioth volumio[1144]: info: CoreStateMachine::getQueue Aug 25 20:13:53 volumioth volumio[1144]: info: CorePlayQueue::getQueue Aug 25 20:13:55 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 25 20:13:55 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:55 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:55 volumioth go-librespot[9219]: go-librespot daemon starting... Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="app state loaded" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=info msg="zeroconf server listening on port 39427" Aug 25 20:13:55 volumioth volumio[1144]: info: Initializing connection to go-librespot Websocket Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="new websocket client" Aug 25 20:13:55 volumioth volumio[1144]: info: Connection to go-librespot Websocket established Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="obtained new client token: AAHZA+hqVi9kYKT8rk2Fj/je0wYzBSSDetlG8xeJB16lv1YZQPMKLHj/vYl94oV+4vWNJbK0T0r80YVbyKWvz/EbCQPeyvrb3i4Dn06Us1/6TEH1sf0MlzPqhLxLBsWqUGJBtGpXByIBEBeyu66n5bPXbYq/efyAFf+ZYGRNZ53QqHFkvrl0uf3GHr8ter2MhD2InUoU726C05AAcQyTH8GxzJZQgRa+XpZYy19MxzX0cLw6Hbeurg==" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="completed keyexchange" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=debug msg="completed challenge" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=info msg="authenticated AP" username="11******07" Aug 25 20:13:55 volumioth go-librespot[9220]: time="2026-08-25T20:13:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 25 20:13:55 volumioth systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 25 20:13:55 volumioth systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 25 20:13:55 volumioth volumio[1144]: info: Connection to go-librespot Websocket closed Aug 25 20:13:56 volumioth volumio[1144]: info: CoreCommandRouter::volumioGetState Aug 25 20:13:56 volumioth volumio[1144]: info: CorePlayQueue::getTrack 0 Aug 25 20:13:58 volumioth volumio[1144]: info: Getting Spotify volume Aug 25 20:13:58 volumioth volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 20:13:58 volumioth volumio[1144]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 25 20:13:58 volumioth volumio[1144]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 25 20:13:58 volumioth volumio[1144]: errno: -111, Aug 25 20:13:58 volumioth volumio[1144]: code: 'ECONNREFUSED', Aug 25 20:13:58 volumioth volumio[1144]: syscall: 'connect', Aug 25 20:13:58 volumioth volumio[1144]: address: '127.0.0.1', Aug 25 20:13:58 volumioth volumio[1144]: port: 9879, Aug 25 20:13:58 volumioth volumio[1144]: response: undefined Aug 25 20:13:58 volumioth volumio[1144]: } Aug 25 20:13:58 volumioth volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 20:13:58 volumioth systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 25 20:13:58 volumioth systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:58 volumioth systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 20:13:58 volumioth go-librespot[9242]: go-librespot daemon starting... Aug 25 20:13:58 volumioth go-librespot[9243]: time="2026-08-25T20:13:58+02:00" level=info msg="running go-librespot 0.7.1" Aug 25 20:13:58 volumioth go-librespot[9243]: time="2026-08-25T20:13:58+02:00" level=debug msg="app state loaded" Aug 25 20:13:58 volumioth go-librespot[9243]: time="2026-08-25T20:13:58+02:00" level=debug msg="stored credentials not found" Aug 25 20:13:58 volumioth go-librespot[9243]: time="2026-08-25T20:13:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=info msg="zeroconf server listening on port 46171" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 20:13:59 volumioth sudo[9254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 20:12' Aug 25 20:13:59 volumioth sudo[9254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=debug msg="obtained new client token: AAE/FfMTQssybqK3XXMthh1rstLX6b3VlTHCpu7hCsBPHLjZdgEotGqb3v/duX+OwQFSI43WjEnC9am7dtjhGmtMaklZrBXcv5jfcW1Td1vqmS4JRwSGM7NQXmz62GcIMH1Wr/3Y07qDCt58Gqz3TrqfrP7+AmfNveiGGi1OUs8RwxFiI2KkGp4NBlDbnqEQ+H8zaaZW2tlGuQpaoPOUr27vj2zB3/r1IngfHUZj31Fn6aoIkCw=" Aug 25 20:13:59 volumioth go-librespot[9243]: time="2026-08-25T20:13:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"