Aug 29 09:26:00 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:00 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:02 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:02 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:03 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:26:03 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 09:26:07 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478733. Aug 29 09:26:07 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:07 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:07 lounge upmpdcli[24952]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:26:07 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:07 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:26:07 lounge go-librespot[1541]: time="2026-08-29T09:26:07+01:00" level=trace msg="received accesspoint ping" Aug 29 09:26:07 lounge go-librespot[1541]: time="2026-08-29T09:26:07+01:00" level=trace msg="received accesspoint pong ack" Aug 29 09:26:10 lounge volumio[1184]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 09:26:12 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:12 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:12 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:12 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:15 lounge volumio[1184]: info: CALLMETHOD: music_service spop logout Aug 29 09:26:15 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: spop , logout Aug 29 09:26:15 lounge volumio[1184]: info: Deleting Spotify credentials File Aug 29 09:26:15 lounge volumio[1184]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 29 09:26:15 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:26:16 lounge volumio[1184]: info: Creating Spotify config file Aug 29 09:26:16 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:26:16 lounge volumio[1184]: info: Spotify config file written Aug 29 09:26:16 lounge sudo[24971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 09:26:16 lounge sudo[24971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:16 lounge systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 29 09:26:16 lounge systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 29 09:26:16 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:16 lounge systemd[1]: go-librespot-daemon.service: Consumed 2month 1w 34min 9.093s CPU time. Aug 29 09:26:16 lounge volumio[1184]: info: Connection to go-librespot Websocket closed Aug 29 09:26:16 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:16 lounge go-librespot[24975]: go-librespot daemon starting... Aug 29 09:26:16 lounge sudo[24971]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:17 lounge go-librespot[24976]: time="2026-08-29T09:26:17+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:26:17 lounge go-librespot[24976]: time="2026-08-29T09:26:17+01:00" level=debug msg="no app state found" Aug 29 09:26:17 lounge go-librespot[24976]: time="2026-08-29T09:26:17+01:00" level=debug msg="stored credentials not found" Aug 29 09:26:17 lounge go-librespot[24976]: time="2026-08-29T09:26:17+01:00" level=info msg="generated new device id: 042769b969673cfd2fea19fc595ddd3a4aeb3666" Aug 29 09:26:17 lounge go-librespot[24976]: time="2026-08-29T09:26:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:26:18 lounge go-librespot[24976]: time="2026-08-29T09:26:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:26:18 lounge go-librespot[24976]: time="2026-08-29T09:26:18+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:26:18 lounge go-librespot[24976]: time="2026-08-29T09:26:18+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:26:18 lounge go-librespot[24976]: time="2026-08-29T09:26:18+01:00" level=info msg="zeroconf server listening on port 33645" Aug 29 09:26:19 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:26:19 lounge go-librespot[24976]: time="2026-08-29T09:26:19+01:00" level=debug msg="new websocket client" Aug 29 09:26:19 lounge volumio[1184]: info: Connection to go-librespot Websocket established Aug 29 09:26:19 lounge volumio[1184]: info: go-librespot daemon successfully initialized Aug 29 09:26:22 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:22 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:22 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478734. Aug 29 09:26:22 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:22 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:22 lounge upmpdcli[25001]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:26:22 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:22 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:26:22 lounge volumio[1184]: info: Getting Spotify volume Aug 29 09:26:23 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:26:23 lounge go-librespot[24976]: time="2026-08-29T09:26:23+01:00" level=debug msg="new websocket client" Aug 29 09:26:23 lounge volumio[1184]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 09:26:23 lounge volumio[1184]: info: Connection to go-librespot Websocket established Aug 29 09:26:23 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:23 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:23 lounge volumio[1184]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Aug 29 09:26:26 lounge volumio[1184]: info: Getting Spotify volume Aug 29 09:26:26 lounge volumio[1184]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 09:26:26 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:26 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:26 lounge volumio[1184]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Aug 29 09:26:32 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:32 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:32 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:32 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:33 lounge sudo[25024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 09:26:33 lounge sudo[25024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:33 lounge sudo[25022]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 09:26:33 lounge sudo[25022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:33 lounge sudo[25024]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:33 lounge sudo[25022]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:34 lounge volumio[1184]: verbose: New Socket.io Connection to 10.10.10.6 from 10.10.10.27 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 09:26:34 lounge sudo[25028]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 09:26:34 lounge sudo[25028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:34 lounge sudo[25030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 09:26:34 lounge sudo[25028]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:34 lounge sudo[25030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:34 lounge sudo[25030]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:35 lounge volumio[1184]: verbose: New Socket.io Connection to 10.10.10.6 from 10.10.10.27 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:35 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 09:26:35 lounge volumio[1184]: info: Received Get System Info Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:26:35 lounge volumio[1184]: info: Discovery: Getting this device information Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:35 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:35 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:35 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 09:26:35 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 09:26:36 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 09:26:37 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:26:37 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478735. Aug 29 09:26:37 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:37 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:37 lounge upmpdcli[25033]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:26:37 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:37 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:26:42 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:42 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:51 lounge volumio[1184]: info: Received OAUTH Data Aug 29 09:26:51 lounge volumio[1184]: info: Executing Spotify Oauth Login Aug 29 09:26:51 lounge volumio[1184]: info: Saving Spotify Refresh Token Aug 29 09:26:51 lounge volumio[1184]: info: New Spotify access tokenBQCOw2axQR... Aug 29 09:26:51 lounge volumio[1184]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 09:26:51 lounge sudo[25066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 09:26:51 lounge sudo[25066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:51 lounge sudo[25066]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:51 lounge sudo[25068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 09:26:51 lounge sudo[25068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:51 lounge sudo[25068]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:51 lounge volumio[1184]: SPOTIFY: User informations: {"account_id":"msViBx9wgU","country":"GB","display_name":"Andy Fair","email":"flibblebot@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cpuwftfqrb56sbmmcuwwvwsz6u"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cpuwftfqrb56sbmmcuwwvwsz6u","id":"31cpuwftfqrb56sbmmcuwwvwsz6u","images":[],"product":"premium","type":"user","uri":"spotify:user:31cpuwftfqrb56sbmmcuwwvwsz6u"} Aug 29 09:26:51 lounge volumio[1184]: info: Creating Spotify config file Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:26:51 lounge volumio[1184]: verbose: New Socket.io Connection to 10.10.10.6 from 10.10.10.27 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 9 Aug 29 09:26:51 lounge volumio[1184]: info: Spotify config file written Aug 29 09:26:51 lounge sudo[25073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 09:26:51 lounge sudo[25073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:26:51 lounge systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 29 09:26:51 lounge systemd[1]: go-librespot-daemon.service: Killing process 24985 (n/a) with signal SIGKILL. Aug 29 09:26:51 lounge systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 29 09:26:51 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:51 lounge volumio[1184]: info: Connection to go-librespot Websocket closed Aug 29 09:26:51 lounge volumio[1184]: info: Connection to go-librespot Websocket closed Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 09:26:51 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:51 lounge go-librespot[25075]: go-librespot daemon starting... Aug 29 09:26:51 lounge sudo[25073]: pam_unix(sudo:session): session closed for user root Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="app state loaded" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="stored credentials not found" Aug 29 09:26:51 lounge volumio[1184]: info: New Spotify access tokenBQBlD0K-cN... Aug 29 09:26:51 lounge volumio[1184]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:51 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 09:26:51 lounge volumio[1184]: info: Received Get System Info Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:26:51 lounge volumio[1184]: info: Discovery: Getting this device information Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:51 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:51 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:51 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 09:26:51 lounge volumio[1184]: SPOTIFY: User informations: {"account_id":"msViBx9wgU","country":"GB","display_name":"Andy Fair","email":"flibblebot@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cpuwftfqrb56sbmmcuwwvwsz6u"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cpuwftfqrb56sbmmcuwwvwsz6u","id":"31cpuwftfqrb56sbmmcuwwvwsz6u","images":[],"product":"premium","type":"user","uri":"spotify:user:31cpuwftfqrb56sbmmcuwwvwsz6u"} Aug 29 09:26:51 lounge volumio[1184]: info: Spotify Successfully logged in Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 09:26:51 lounge volumio[1184]: info: [1787992011702] CoreMusicLibrary::Adding element Spotify Aug 29 09:26:51 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:26:51 lounge volumio[1184]: Cannot find translation for source Spotify Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=info msg="zeroconf server listening on port 44989" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="obtained new client token: AAEPCo7GvtO2ys2KltoMyMpdG/L6AdrIfCmkeOEsSUCMNOWKyUmC5wPqQwcFGuJ4evzVSELdl3caarnDLzC3N+Rk508PSxq6e0zv04DmxdvHbWGMUAj/toFDBHboHEKWu4ztkGsdVRQfrqDyNDgd4YivZHHhVVg8Xz8K+gsDf3+h5yLM+OOz6Ug+FvpGbPePHtDJ2Vx27L4Yxhiovo7ytPT26n7Y2+bTAFOeIMpSqgO3cvfc4+mOWEYc" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:26:51 lounge go-librespot[25076]: time="2026-08-29T09:26:51+01:00" level=debug msg="completed keyexchange" Aug 29 09:26:52 lounge go-librespot[25076]: time="2026-08-29T09:26:52+01:00" level=debug msg="completed challenge" Aug 29 09:26:52 lounge go-librespot[25076]: time="2026-08-29T09:26:52+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:26:52 lounge go-librespot[25076]: time="2026-08-29T09:26:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:26:52 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:52 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:26:52 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:52 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:52 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:52 lounge volumio[1184]: info: Listing playlists Aug 29 09:26:53 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478736. Aug 29 09:26:53 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:53 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:26:53 lounge upmpdcli[25085]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:26:53 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:53 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:26:53 lounge volumio[1184]: info: Received Get System Info Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:26:53 lounge volumio[1184]: info: Discovery: Getting this device information Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:53 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:53 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:26:54 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:26:54 lounge volumio[1184]: info: Received Get System Info Aug 29 09:26:54 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:26:54 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:26:54 lounge volumio[1184]: info: Discovery: Getting this device information Aug 29 09:26:54 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:54 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:54 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:26:54 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:26:54 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:26:54 lounge volumio[1184]: info: go-librespot daemon successfully initialized Aug 29 09:26:55 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 29 09:26:55 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:55 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:55 lounge go-librespot[25086]: go-librespot daemon starting... Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="app state loaded" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="stored credentials not found" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=info msg="zeroconf server listening on port 45337" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="obtained new client token: AAHEWxgnJBDoAMchanceASIVOrjNR7DecFNKnt1cyfLyf75UjGYeDu8btVGpFUf75ybj04IMpW9I+iIOEm8jkQexaRqDLg+m4qss5JGT71Ejv0CimhLN6Lsd3v1HoHK3cCtG+CY0UJmBWbgE5gPE8m66wmfyO8yFxwaI0SRCr2KufsF3txET4Q+xyT2+WlHzk5piZlXcUO9SfrOtZR5tQRxtAMDJcaTi77TATePTcXldJN9rWITEsFOP" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="completed keyexchange" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=debug msg="completed challenge" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:26:55 lounge go-librespot[25087]: time="2026-08-29T09:26:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:26:55 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:55 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:26:56 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:26:56 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:26:57 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:26:57 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:26:57 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:26:57 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:26:59 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 29 09:26:59 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:59 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:26:59 lounge go-librespot[25112]: go-librespot daemon starting... Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="app state loaded" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="stored credentials not found" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=info msg="zeroconf server listening on port 43463" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="obtained new client token: AAGjHvuuAhWF2jjEg9HG4FTQa7sF73CBrOi/iZyXDT6x4HwN04zHqt64r8BZccg0epY+I6cTYANDiT7b6mGWq7xSWc2nQkg6WqPdT4OG3zju+aPIDLekteve7od4Xk1pVzZcRPKkJ0e7ShPaSK7y0LXit6ziowxmBi9O7U5CyyeRJzWTyqopN6m1jI7ce9BDt8kPNioMUA7+hUc/dSsffnsMN55oDJfI/FvqDK+r1lsMxBaV46XuJFAj" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="completed keyexchange" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=debug msg="completed challenge" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:26:59 lounge go-librespot[25113]: time="2026-08-29T09:26:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:26:59 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:26:59 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:00 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:00 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:00 lounge volumio[1184]: info: CoreCommandRouter::volumioGetQueue Aug 29 09:27:00 lounge volumio[1184]: info: CoreStateMachine::getQueue Aug 29 09:27:00 lounge volumio[1184]: info: CorePlayQueue::getQueue Aug 29 09:27:01 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 09:27:02 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:27:02 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:27:02 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 29 09:27:02 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:02 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:02 lounge go-librespot[25122]: go-librespot daemon starting... Aug 29 09:27:02 lounge go-librespot[25123]: time="2026-08-29T09:27:02+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:02 lounge go-librespot[25123]: time="2026-08-29T09:27:02+01:00" level=debug msg="app state loaded" Aug 29 09:27:02 lounge go-librespot[25123]: time="2026-08-29T09:27:02+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:02 lounge go-librespot[25123]: time="2026-08-29T09:27:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=info msg="zeroconf server listening on port 46233" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="obtained new client token: AAFbHUYT7F3C8f0HLXu9us17UVCn0htXKqAPLvep7bhaiOjDVWiIsRDFaSkE286K8n+HWAuhlhFANisZ1tltfziP3TAhJMlQt/D4QXFCiGXtu6LBTJhxosUrhD03J7Ao/VnuMiCL0/KWQ3BdF0DGBDoisSaGjOJR7PoGE/jNKF1dPa22S6LCqeszBJQ48b8rYYdninwAd9qdQAmTowYOGcGTUbX5sNB7LogCo2oWnaUhSU6KD0Fghg==" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=debug msg="completed challenge" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:03 lounge go-librespot[25123]: time="2026-08-29T09:27:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:03 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:03 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:03 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:03 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:06 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:27:06 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:27:06 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 29 09:27:06 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:06 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:06 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:06 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:06 lounge go-librespot[25135]: go-librespot daemon starting... Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="app state loaded" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=info msg="zeroconf server listening on port 41051" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="obtained new client token: AAHlS5aBuEfHuBIcz/napBcPTvX7JnqSU4G/BXtPoMXK3YE/btIPwGWhBis7WUBBIM3PSAAMF13wcEiraDQglbzcGkAt98eocWS0494s2uIVVTTMJFE8gVbPxLb7Vk/BMN0vYGeFvKv21F9B13WKQX9WOkCGqMZzrQg6QbIkgoQ0RPesVa1D9u2tJgLvwMhhQ08oGblsKxJ6m2w/k/QjnEfD7kQfH87F+qdF0lnped/2qUDPgHaFG9AD" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=debug msg="completed challenge" Aug 29 09:27:06 lounge go-librespot[25136]: time="2026-08-29T09:27:06+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:07 lounge go-librespot[25136]: time="2026-08-29T09:27:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:07 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:07 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:07 lounge volumio[1184]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 29 09:27:07 lounge volumio[1184]: info: In handleBrowseUri, curUri=spotify Aug 29 09:27:07 lounge volumio[1184]: info: Preload queue cleared Aug 29 09:27:07 lounge volumio[1184]: info: Preload queue cleared Aug 29 09:27:07 lounge volumio[1184]: info: Preload queue cleared Aug 29 09:27:07 lounge volumio[1184]: info: Preload queue cleared Aug 29 09:27:08 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478737. Aug 29 09:27:08 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:08 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:08 lounge upmpdcli[25146]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:27:08 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:08 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:27:09 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:09 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:10 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 29 09:27:10 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:10 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:10 lounge go-librespot[25161]: go-librespot daemon starting... Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="app state loaded" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=info msg="zeroconf server listening on port 40317" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="obtained new client token: AAECSQst+6Tj0ED5h9t8xzYVYWA+M1XFtSBsYSL1W0QvwNsI29TZeQ5kwPPoRuOEkrGjPNEHlhgwu13a3+QhUgcf1pJGGh1E1oxmwoqr7fKCy1ulHNu+JJXBO9WhScNaFJuX4zbkkiQw/CoMfaOSqkjq7gUQzqVS9of8qmoVdidPbHjNnpresr8TIHK+Q5Eh1Gz2nqdG3wAFkc6lItoA9u+9KYO0PnOPGEPmKuZaf+SK2/luoCMRSNaB" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=debug msg="completed challenge" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:10 lounge go-librespot[25162]: time="2026-08-29T09:27:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:10 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:10 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:12 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:27:12 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:27:12 lounge volumio[1184]: info: Listing playlists Aug 29 09:27:12 lounge volumio[1184]: info: Listing playlists Aug 29 09:27:12 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:12 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:14 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 29 09:27:14 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:14 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:14 lounge go-librespot[25171]: go-librespot daemon starting... Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="app state loaded" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=info msg="zeroconf server listening on port 35551" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="obtained new client token: AAHzW3GLB3+wTBMATs1rYx9zKX2RIDxnN8EdEJB/HaLh0WhJAwc3uH4RCd4SM7E4E6sPMODvVppTxJs0qMudb5Hqgm8GYaqPYn7QotVuLHKr0AM7tKZYwmC4U3N1zFpFmClvgOVAidu36tL0BNTvFkE3r9lzzkI5UQIlJ37Xmn3MkOSH7pWohE3l+UiOH1LYYmPdICaDng4NxqlPrYHFH3pq5s31LcC2yO/KEp7801FZGsDT16yjwKFT" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=debug msg="completed challenge" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:14 lounge go-librespot[25172]: time="2026-08-29T09:27:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:14 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:14 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:15 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:15 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:17 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 29 09:27:17 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:17 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:17 lounge go-librespot[25182]: go-librespot daemon starting... Aug 29 09:27:17 lounge go-librespot[25183]: time="2026-08-29T09:27:17+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:17 lounge go-librespot[25183]: time="2026-08-29T09:27:17+01:00" level=debug msg="app state loaded" Aug 29 09:27:17 lounge go-librespot[25183]: time="2026-08-29T09:27:17+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:17 lounge go-librespot[25183]: time="2026-08-29T09:27:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=info msg="zeroconf server listening on port 39931" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="obtained new client token: AAG/Gwc8pEIcMWqzmI7Ias7s5hwYtMl0/LsM4f1kS45/sq7VT+i0CYaSQTtGuLYhRGz7Oi0yRtwd5HdBPse2n7agUJogQk+C3XCQaDGZcPyKzsuyBo1WIG6dbUg1tJ23cpllt8Pi4qDbILjEeawFxlMGKWm9xvBjqRktUapKiYVRrz633tLXhDQ59RPs80HdSzvDi0rh7jqLbQbR5AwJw2HZKeCEug0MlJxW9BHci/z8Qkis8iZiRQ==" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=debug msg="completed challenge" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:18 lounge go-librespot[25183]: time="2026-08-29T09:27:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:18 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:18 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:18 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:18 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:21 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 29 09:27:21 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:21 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:21 lounge go-librespot[25206]: go-librespot daemon starting... Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="app state loaded" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:21 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:21 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=info msg="zeroconf server listening on port 36465" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="obtained new client token: AAEn23GmHJRXyrI8l56tjtP3Uwxqflkdq5Xgw9Wuw9zvNq1/Tb/A7ZaNMa+Om8JX6oBjnqr9AoWsyjbk1c7/R2iIJn0uNjcM+GmQeMuEm2O3L9kaWz+tKCQDGaHrEJsC1OzyBpdsErNig4OoRaiPnScbU0r9VBjPDevA8kWKfMdrL/EzvJMKClko8+kLz+GQ6s/nNcvYuunW1mesJKZ5lX+66E4MpOzBV5F1u+hbOIlbiM3LrmEjFmP5" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=debug msg="completed challenge" Aug 29 09:27:21 lounge go-librespot[25207]: time="2026-08-29T09:27:21+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:22 lounge go-librespot[25207]: time="2026-08-29T09:27:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:22 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:22 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:22 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:27:22 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:27:23 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478738. Aug 29 09:27:23 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:23 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:23 lounge upmpdcli[25219]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:27:23 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:23 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:27:24 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:24 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:25 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 29 09:27:25 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:25 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:25 lounge go-librespot[25220]: go-librespot daemon starting... Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="app state loaded" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=info msg="zeroconf server listening on port 36725" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="obtained new client token: AAGxTsYeydxFkN+direQ8zEThnma01IcyUxsnjeniNajjD3jeUzyijFwI5wQw/0RJvZ2GPP3zKj6U46SDgmoh4fhaI4PKxPsyc+2+3VC7s9lCSyjtjCuFr1TMjtw1kaqA/qJq6+e13BXIthC7ylskRLFC92vamJ5a2r+qMg4nJ6CFT3rWgWhajFBmUmzgO6hGYVs3ctYj716J8IOtOlpXswW5fgpj06oEdnsDZe8//6O78A1VO2oU4EH" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=debug msg="completed challenge" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:25 lounge go-librespot[25221]: time="2026-08-29T09:27:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:25 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:25 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:27 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:27 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:29 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 29 09:27:29 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:29 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:29 lounge go-librespot[25244]: go-librespot daemon starting... Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="app state loaded" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=info msg="zeroconf server listening on port 40093" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="obtained new client token: AAFavg8IHPFVDM5XJl0b4/fXF9w59LKzOtda9CGZXzeJm/IKSD1DK1Hz8I4xBH68UI0GZ2ebCNHB1vDDb0W+aII3yEDg/6V0MVlIJ6u3KrfEhByOwcCGO/Z2sqf5AO9bX2GD29HCpc3Y3mLVHG3hzMkf/k7g7PaotK4VV128drjX92f0yVaEYwkYWwmV6EIE5W0qj1dLl/wKxC5Z0UK5ARUaIrpw15K/PtDDn40fIFfJJ56IZr9j72I8" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=debug msg="completed challenge" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:29 lounge go-librespot[25245]: time="2026-08-29T09:27:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:29 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:29 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:30 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:30 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:32 lounge volumio[1184]: info: CoreCommandRouter::volumioGetState Aug 29 09:27:32 lounge volumio[1184]: info: CorePlayQueue::getTrack 0 Aug 29 09:27:32 lounge volumio[1184]: info: Listing playlists Aug 29 09:27:32 lounge volumio[1184]: info: Listing playlists Aug 29 09:27:32 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 29 09:27:32 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:32 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:32 lounge go-librespot[25254]: go-librespot daemon starting... Aug 29 09:27:32 lounge go-librespot[25255]: time="2026-08-29T09:27:32+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:32 lounge go-librespot[25255]: time="2026-08-29T09:27:32+01:00" level=debug msg="app state loaded" Aug 29 09:27:32 lounge go-librespot[25255]: time="2026-08-29T09:27:32+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:32 lounge go-librespot[25255]: time="2026-08-29T09:27:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=info msg="zeroconf server listening on port 37525" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="obtained new client token: AAGWesHmXQj9elUJSQc1JAjYhmWem9E16fXG/Urb771Ree3gM+hVv9id/UQZXC8+q52mUM2cPWJXZag4fJUKxvapmg0z9hBVxgKXjOl9qpx0dWwHgVJUqkCMbDImkwkUQY2ggZhBrGqj1zpfCnWBohHQ5/vSZHSyZAL5jkUccMl/1xyNQdeSdS+TItA6saDhfhOIDXNpt4rdxUDMiU1JssoViQa/wbYlJpZANiaj5wklmaXzIEUf1A==" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=debug msg="completed challenge" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:33 lounge go-librespot[25255]: time="2026-08-29T09:27:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:33 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:33 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:33 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:33 lounge volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:36 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 29 09:27:36 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:36 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:36 lounge go-librespot[25265]: go-librespot daemon starting... Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="app state loaded" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:36 lounge volumio[1184]: info: Initializing connection to go-librespot Websocket Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="new websocket client" Aug 29 09:27:36 lounge volumio[1184]: info: Connection to go-librespot Websocket established Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=info msg="zeroconf server listening on port 41001" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="obtained new client token: AAECOrtewILvhIcul4G+FGFieHXYo3iPrqIPjZzy0hOweEVU6fjc+6Vdw1NuL+asWyGI0Lp/0nY3482qe6aqa5LIpTyzIL4otpOYsu/rzfFrZgppYquerDOyQsELxqdP6LVBQh/WJ1DM6bOHggWMlB2XF84hRbYl6KSSBOJkFTkvO004iSe1zcgoUJadtKnjjZoZT//4/DPri4Ne2oVyELtjOdSNlqPtPbFS3VbIgkAoKZNEsY0q0aKj" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=debug msg="completed challenge" Aug 29 09:27:36 lounge go-librespot[25266]: time="2026-08-29T09:27:36+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:37 lounge go-librespot[25266]: time="2026-08-29T09:27:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:37 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:37 lounge volumio[1184]: info: Connection to go-librespot Websocket closed Aug 29 09:27:37 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:38 lounge systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 478739. Aug 29 09:27:38 lounge systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:38 lounge systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 09:27:38 lounge upmpdcli[25289]: Could not open config: /tmp/upmpdcli.conf Aug 29 09:27:38 lounge systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:38 lounge systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 09:27:39 lounge volumio[1184]: info: Getting Spotify volume Aug 29 09:27:39 lounge volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 09:27:39 lounge volumio[1184]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:27:39 lounge volumio[1184]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 29 09:27:39 lounge volumio[1184]: errno: -111, Aug 29 09:27:39 lounge volumio[1184]: code: 'ECONNREFUSED', Aug 29 09:27:39 lounge volumio[1184]: syscall: 'connect', Aug 29 09:27:39 lounge volumio[1184]: address: '127.0.0.1', Aug 29 09:27:39 lounge volumio[1184]: port: 9879, Aug 29 09:27:39 lounge volumio[1184]: response: undefined Aug 29 09:27:39 lounge volumio[1184]: } Aug 29 09:27:39 lounge volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 09:27:40 lounge systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 29 09:27:40 lounge systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:40 lounge systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:27:40 lounge go-librespot[25293]: go-librespot daemon starting... Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=info msg="running go-librespot 0.6.2" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="app state loaded" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="stored credentials not found" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=info msg="zeroconf server listening on port 45419" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="obtained new client token: AAG3bX00NZ48O4WrfA/gBSFCJAcmRhH0xhevf2ABmTJWKq/ti7BjeS+wKUAYsHCUYBckBCf3UNnj7NbtnsDpebMLDHrGqAQ9Q2KdGDmiZxhfqRuHmIIh5ievwmhzptnG4w80JpIJmWDkUx6YDdB5K9fihvZXzTe+OjPQ5d4SdigbQq8yYmfZA2vlmm9FHs1Crd2T2Z+37Ln8+JUrfANz7LvRdHIsr4KGnWBgrkQyanU6HrWK0IIs4d3v" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="completed keyexchange" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=debug msg="completed challenge" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=info msg="authenticated AP" username="31************************6u" Aug 29 09:27:40 lounge go-librespot[25294]: time="2026-08-29T09:27:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:27:40 lounge systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:27:40 lounge systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:27:41 lounge sudo[25314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 09:26' Aug 29 09:27:41 lounge sudo[25314]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"