Dec 10 08:22:00 gbvpi-bedroom systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 08:22:00 gbvpi-bedroom sudo[2039]: pam_unix(sudo:session): session closed for user root Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Executing endpoint tc_getconfig Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 08:22:00 gbvpi-bedroom vtcs[2042]: STARTING TidalConnect services, version: 1.5.2.56 Dec 10 08:22:00 gbvpi-bedroom vtcs[2042]: STARTED TidalConnect services. Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Executing endpoint tc_connect Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Connecting to TidalConnect Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::servicePushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreStateMachine::pushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioPushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CorePlayQueue::getTrack 0 Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received tidalconnect Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::servicePushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreStateMachine::pushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioPushState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: CorePlayQueue::getTrack 0 Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received tidalconnect Dec 10 08:22:00 gbvpi-bedroom sudo[2073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 08:22:00 gbvpi-bedroom sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 08:22:00 gbvpi-bedroom systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 08:22:00 gbvpi-bedroom systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 08:22:00 gbvpi-bedroom systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status' Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:00 gbvpi-bedroom systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 08:22:00 gbvpi-bedroom mpd_monitor.sh[2075]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 08:22:00 gbvpi-bedroom sudo[2073]: pam_unix(sudo:session): session closed for user root Dec 10 08:22:00 gbvpi-bedroom volumio[1197]: info: Successfully started MPD Monitor Dec 10 08:22:00 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 10 08:22:00 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:01 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:01 gbvpi-bedroom go-librespot[2079]: go-librespot daemon starting... Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="app state loaded" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=info msg="zeroconf server listening on port 44053" Dec 10 08:22:01 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:01 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="obtained new client token: AAC4FhkE3RjiEj6W1cienxBhiIpn2vumAA2D+krdcO/CUK6du34+gz3vC31B9wiHi40O7vS8WLQ1rHFa4g5hjKFUm0rAMMB3k1udhLYtkEUAz/8DOgVzNPaCpncX2qCCVYcNKEqi6FpuphpIGPQdHcNpdPjDtL5fdXNCIjDBtIhMuoMTOy2+3xKHijjcgruBUo9lVvalUMBfmqHd+B3eX9K/YvADFBY/Z7Cl7ZedhDehCXxOsDMhsRX7Hw==" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=debug msg="completed challenge" Dec 10 08:22:01 gbvpi-bedroom go-librespot[2080]: time="2025-12-10T08:22:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:01 gbvpi-bedroom volumio[1197]: info: Discovery: adding 2e705aa1-b77a-46e6-a814-faa1d0e00039 Dec 10 08:22:01 gbvpi-bedroom volumio[1197]: info: Discovery: Found device undefined Dec 10 08:22:01 gbvpi-bedroom volumio[1197]: info: Discovery: Connecting to remote: 192.168.1.28 Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: TidalConnect service started! Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: [Metrics] CommandRouter: 68s 506.67ms Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumiosetStartupVolume Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::Close All Modals sent Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::Close All Modals sent Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:03 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: Version has changed, forcing UI Reload Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::Reload Ui Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: MRS: Pushing audio output on request Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: Discovery: Connected to remote: 192.168.1.28 Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 08:22:04 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:04 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 10 08:22:04 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:04 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:04 gbvpi-bedroom go-librespot[2091]: go-librespot daemon starting... Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=debug msg="app state loaded" Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:04 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:04-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=info msg="zeroconf server listening on port 34063" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=debug msg="obtained new client token: AAApIuiSMTS/edESleFKdbo1S9PHxvXVC3HYcC8HnIlNs9SyEzkszYBdGLIo6BPIXPFKYiHpRVbzIgCpaoY/uQc5In0fCQVZQLNfOdtGiSEG8jQ1bZ6VCFOXTTg1J++6sdqQSjRZSxiylU5ivy3Le2OeZEXvE390W8/gJRp86IgZvoDIH21gxn3uGdLmElY3kT+gCC7t6NhAc2yUxjRDIMSomELyTHceIuNkUlLNpwK6rePpb+se5BY=" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=debug msg="completed challenge" Dec 10 08:22:05 gbvpi-bedroom go-librespot[2092]: time="2025-12-10T08:22:05-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:06 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:06 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:06 gbvpi-bedroom sudo[2108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 08:22:06 gbvpi-bedroom sudo[2108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 08:22:06 gbvpi-bedroom sudo[2108]: pam_unix(sudo:session): session closed for user root Dec 10 08:22:07 gbvpi-bedroom sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 08:22:07 gbvpi-bedroom sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 08:22:07 gbvpi-bedroom sudo[2111]: pam_unix(sudo:session): session closed for user root Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.202 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: Received Get System Info Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: Discovery: Getting this device information Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 10 08:22:07 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 10 08:22:08 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 10 08:22:08 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:08 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:08 gbvpi-bedroom go-librespot[2138]: go-librespot daemon starting... Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="app state loaded" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: Received Get System Version Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=info msg="zeroconf server listening on port 42945" Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="obtained new client token: AADpOb5P0zBo4Rumr8PsYqkun3B11dW36lmXTV0vFhBcHYm2N1QL0z4yvtEMwf2XGMv/UJujm4Xe3Q/YWWb7tfym9pzl5r3EVFs2Q1p23oIzgtBrF6Fc3FpL+DeaGC8dIfLI7jMFZ17HydDv7L9JhJAPWf6B9nkPHNtd4VyJqsAPr189JWqAZizAx/HJWOjRFeZdd1mvic9RhwVrjD2xS9ZO2NGbVrLO2hPRLlK4BCYA0qPhlooLKqr45Q==" Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: Received Get System Info Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 08:22:08 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:08-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: Discovery: Getting this device information Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:08 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 08:22:09 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:09-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:09 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:09-05:00" level=debug msg="completed challenge" Dec 10 08:22:09 gbvpi-bedroom go-librespot[2139]: time="2025-12-10T08:22:09-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: Received Get System Info Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: Discovery: Getting this device information Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:09 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 08:22:10 gbvpi-bedroom volumio[1197]: info: BOOT COMPLETED Dec 10 08:22:10 gbvpi-bedroom volumio[1197]: info: Discovery: Disconnected from remote: 192.168.1.28 Dec 10 08:22:11 gbvpi-bedroom volumio[1197]: info: Discovery: A device disappeared from network Dec 10 08:22:11 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:11 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 10 08:22:12 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:12 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:12 gbvpi-bedroom go-librespot[2162]: go-librespot daemon starting... Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="app state loaded" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=info msg="zeroconf server listening on port 36491" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="obtained new client token: AAAGCkuQaC3IOuVRLYdl0k400ahpM5s1V1gYiYaJto1Ah66o5BbwlWa1z3y0lELZqcygkHovhnW/yB+AMHxW8zu4oDyHoPiNXJh4+k9/jii0EgKWjHfu2qJ/iQtI5KeJFr6J/+AsmXPJ2/OgY3Eg2MO++ZUCawiXoVAqkojN54gsULddigQibGHym6AVyJMHboT3j3Fgj2mwiseVqLF9B55cq77IOMmTq7On4ET4nbjdKBgxt/cGDN1u2w==" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=debug msg="completed challenge" Dec 10 08:22:12 gbvpi-bedroom go-librespot[2163]: time="2025-12-10T08:22:12-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:12 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:12 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:15 gbvpi-bedroom volumio[1197]: info: Discovery: adding 2e705aa1-b77a-46e6-a814-faa1d0e00039 Dec 10 08:22:15 gbvpi-bedroom volumio[1197]: info: Discovery: Found device undefined Dec 10 08:22:15 gbvpi-bedroom volumio[1197]: info: Discovery: Connecting to remote: 192.168.1.28 Dec 10 08:22:15 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:15 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:15 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 10 08:22:15 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:16 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:16 gbvpi-bedroom go-librespot[2171]: go-librespot daemon starting... Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="app state loaded" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=info msg="zeroconf server listening on port 45937" Dec 10 08:22:16 gbvpi-bedroom volumio[1197]: info: MRS: Pushing audio output on request Dec 10 08:22:16 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:16 gbvpi-bedroom volumio[1197]: info: Discovery: Connected to remote: 192.168.1.28 Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="obtained new client token: AAA+snINvFmFX+OxvH7Q/IjsV+GsnbpJYU8+S+IXEF5wqc1CL/vNd+7zbR3gPy53xet23viicjkrwZx8mEgrWTuEyYetaU8hG5I5YSpbIl/RfnY53x0LnVMhXyML8tr3L72r9+AUkRxoS5yTDanNCXKMZg7tM8EyM8eFwLEp79SHz+poDuUTj7oCvARG3+iYjryTwF93FFbA0kLGt1LKz90viRBZvkjLe7f1lVvz2lPiBgwzeI3V00mTmg==" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:16 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=debug msg="completed challenge" Dec 10 08:22:16 gbvpi-bedroom go-librespot[2172]: time="2025-12-10T08:22:16-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:17 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 08:22:17 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:17 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 10 08:22:18 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:18 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:19 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 10 08:22:19 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:19 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:19 gbvpi-bedroom go-librespot[2179]: go-librespot daemon starting... Dec 10 08:22:19 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:19-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:19 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:19-05:00" level=debug msg="app state loaded" Dec 10 08:22:19 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:19-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=info msg="zeroconf server listening on port 43579" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="obtained new client token: AADfOBJYu3KQLERu5uZ70O9NdHGsu4ohsbLD2oFR92KB9JqUkIMZxMCG4aM6DceuLRl4OgyTUfkfwIOwXzQdfWHsj0bv6fYFhSJSDoNuMCBRBgDGqy0YoLwbrW5CgAuJFWAVaXODj65gHv5XWF6mSb0JgdqLDvC/QwxmMwf8LdyzrU30TFvBjoY2K4nRPAFAlkhfBVwJX0aLjysM/vhkdXY/uAz0eN93+oxV3iz36JxszD/f4gm/sfI=" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=debug msg="completed challenge" Dec 10 08:22:20 gbvpi-bedroom go-librespot[2180]: time="2025-12-10T08:22:20-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:21 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:23 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 10 08:22:23 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:23 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:23 gbvpi-bedroom go-librespot[2204]: go-librespot daemon starting... Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="app state loaded" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:23 gbvpi-bedroom volumio[1197]: info: Discovery: Disconnected from remote: 192.168.1.28 Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=info msg="zeroconf server listening on port 35463" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="obtained new client token: AACqzw5wWlUihS6beH8We4ywLC4CrzYSfceoffXapXZG2tE3pn9lLgTxqL3rK9knErcBNqwQIRH4iw8oWjTygJkb3lg/FARJb9UiGLT05ai7bYSSrvxTNQCpLdbxSz3nuqq0tNAkJDucbHzEMdgRVXUnUGzafWkwMByfop+QhBfiLlT85uY/c6AlsjP6FMJApKtTS7lAYXUdsTKYqiZpcyS8nvp/v0NNJPYf0owDmA7xj3TFUBoFuDTiZw==" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:23 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:23-05:00" level=debug msg="completed challenge" Dec 10 08:22:24 gbvpi-bedroom go-librespot[2205]: time="2025-12-10T08:22:24-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:24 gbvpi-bedroom volumio[1197]: info: Discovery: Remote connection error: 192.168.1.28 Dec 10 08:22:24 gbvpi-bedroom volumio[1197]: info: Discovery: A device disappeared from network Dec 10 08:22:24 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:24 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: Received Get System Version Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: Received Get System Info Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: Discovery: Getting this device information Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:25 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 08:22:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 10 08:22:27 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:27 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:27 gbvpi-bedroom go-librespot[2212]: go-librespot daemon starting... Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="app state loaded" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=info msg="zeroconf server listening on port 42733" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="obtained new client token: AAC6VANcBLKRs5bts0RtfqZaaHmzIW+XOkh2v/ETVvw5GNDKNCzwuCESUVwqx4QK7YSk8yz02QS8Er5cy/TI2WYeqAIYkSLkDBCLCYst1etIP2y51UUNnUO3KYT+23bvGQKB52AKLqvS04LJcvCVVh7uNNX30QDRzmVyEwJ+3e6PzNNzCMZQUE2s6QFDMTxL1Yg64ZOZUeKDOPzXYQeYMx2w70nVh0vDorWYEwYSyIfO0UE8UT4ouQBu9A==" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=debug msg="completed challenge" Dec 10 08:22:27 gbvpi-bedroom go-librespot[2213]: time="2025-12-10T08:22:27-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:27 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:27 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:28 gbvpi-bedroom volumio[1197]: info: Discovery: adding 2e705aa1-b77a-46e6-a814-faa1d0e00039 Dec 10 08:22:28 gbvpi-bedroom volumio[1197]: info: Discovery: Found device undefined Dec 10 08:22:28 gbvpi-bedroom volumio[1197]: info: Discovery: Connecting to remote: 192.168.1.28 Dec 10 08:22:29 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 10 08:22:29 gbvpi-bedroom volumio[1197]: info: MRS: Pushing audio output on request Dec 10 08:22:29 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:29 gbvpi-bedroom volumio[1197]: info: Discovery: Connected to remote: 192.168.1.28 Dec 10 08:22:30 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:30 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:30 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 10 08:22:30 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:31 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 08:22:31 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:31 gbvpi-bedroom go-librespot[2235]: go-librespot daemon starting... Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="app state loaded" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:31 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=info msg="zeroconf server listening on port 37763" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="obtained new client token: AADvptPLCKtIeLuGreoO1VvofdeVPH0wdoZsXhGiMG4fWXm+SHvTI5xOMPuoTNu8D2ZJPkfzWqYS/6uBM4PcIcwm92+h7PKQtOSge/jm1kANo8Be4TcbuPxA/oznOKKRrGZ1qEIeoVBOmtCKxxMZ83CWMsENvsKAMqwgxgYrlDzX0I40aAyxKm0WKhzyjtXbymwcNkUl4sG3JGoK9TbPIRLgceAEjJOFQkeMglyEeM8tsJY5LCangLN8GQ==" Dec 10 08:22:31 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:31 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=debug msg="completed challenge" Dec 10 08:22:31 gbvpi-bedroom go-librespot[2236]: time="2025-12-10T08:22:31-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:33 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:33 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:34 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 10 08:22:34 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:34 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:34 gbvpi-bedroom go-librespot[2243]: go-librespot daemon starting... Dec 10 08:22:34 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:34-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:34 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:34-05:00" level=debug msg="app state loaded" Dec 10 08:22:34 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:34-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=info msg="zeroconf server listening on port 44683" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="obtained new client token: AACf+QG75x91ZhcjdQQrV8tItuBontvN2cegYxAxsbAwpt6I2JTVQCZftPY78TFncT3utBAiFE4377qEvW8fLQfCTh2p6zmgseDhxo50vARVyHN/5gDUUYlbQlAaor1RujfxLAmmGBi35P4J0rFYw1r32XJcdw8+jaGbh2cp/+U3Q2+UMNl7Xjpu3NraHHtFoQoZb3n48d8Zulby2OFNodegf+YkoAjFQSW6aJu4nV4mx+8254uC6iY=" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=debug msg="completed challenge" Dec 10 08:22:35 gbvpi-bedroom go-librespot[2244]: time="2025-12-10T08:22:35-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:36 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:36 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:36 gbvpi-bedroom volumio[1197]: info: Discovery: Disconnected from remote: 192.168.1.28 Dec 10 08:22:37 gbvpi-bedroom volumio[1197]: info: Discovery: Remote connection error: 192.168.1.28 Dec 10 08:22:38 gbvpi-bedroom volumio[1197]: info: Discovery: A device disappeared from network Dec 10 08:22:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 10 08:22:38 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:38 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:38 gbvpi-bedroom go-librespot[2251]: go-librespot daemon starting... Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="app state loaded" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=info msg="zeroconf server listening on port 35025" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="obtained new client token: AABkj8pSRTt2OQONQnRqpAvKpN3YY7VCPHHMRnTAUZ9y6ctIVg1uLXM9PHorXcE2MH81o+9bO3QBkWO+3nEOBk3tzQSMId1QxkhKJXNG7TUHBXfP8BweXUwdTTplfU4LOFl5BaZQ+pIb0LUgbieCWrQVKnMwHQFLJbXsD1tlk8wWnnPNVQ0NQsGZLAyODR+l03EFd8ovrl3uIKnzi2jaHUPD9Zk+vz6WUuOxcN+p373qJOdaQL48prLYeg==" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:38 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:38-05:00" level=debug msg="completed challenge" Dec 10 08:22:39 gbvpi-bedroom go-librespot[2252]: time="2025-12-10T08:22:39-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:39 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:39 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Discovery: adding 2e705aa1-b77a-46e6-a814-faa1d0e00039 Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Discovery: Found device undefined Dec 10 08:22:41 gbvpi-bedroom volumio[1197]: info: Discovery: Connecting to remote: 192.168.1.28 Dec 10 08:22:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 10 08:22:42 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:42 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:42 gbvpi-bedroom go-librespot[2278]: go-librespot daemon starting... Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="app state loaded" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=info msg="zeroconf server listening on port 34491" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="obtained new client token: AABE8TY6fiJSdrL0caAHCknjk+WCPbO3tHN6X6qjR1OhOSUw+xHjl7UBslBFr6QkcFYyHAa7XfLSfLOaqi5j0dA7SxHrZGhyS1t9f/W+KwukKr6VZXA1T1jODQWLEgZJBnEUguHGdVtNnxFuHoHXl1F2Sp55r9dG/NA43RoI5St+eDsouJvsL38vfR43om7Iaxcz/hZrSv1uGNc5awIb7yjTCGsHgff8BFI0fJ5r5o3ku1LJAWoLdt+7aA==" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=debug msg="completed challenge" Dec 10 08:22:42 gbvpi-bedroom go-librespot[2279]: time="2025-12-10T08:22:42-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:42 gbvpi-bedroom volumio[1197]: info: MRS: Pushing audio output on request Dec 10 08:22:42 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:42 gbvpi-bedroom volumio[1197]: info: Discovery: Connected to remote: 192.168.1.28 Dec 10 08:22:43 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:43 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:43 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 10 08:22:44 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 08:22:44 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:45 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 10 08:22:45 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:46 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:46 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:46 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:46 gbvpi-bedroom go-librespot[2286]: go-librespot daemon starting... Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="app state loaded" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=info msg="zeroconf server listening on port 36149" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="obtained new client token: AAAogz0ui+u7CqYZzHCioZ9y0H1P3fH9hu/524GH7McyZ6LI62Tro0mIiMhC8/5ym+WUzXtwZ/nrFJTB0Gw0ypfIMPP+6+xQolSUuht1mB2ZUsz/i4xDJb/7UNgBdAylDdqMG4R6BSp9pRYkwOcvWtifEThSX4CWbGS9YZNvmt8WchVIMsvPgzlgwdaMUuEKnpmLjCp69S3OsZlndjla/isOPUM9RAmXd2WMb3QJ6vzWF0Rg6aJGR7HQ9Q==" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=debug msg="completed challenge" Dec 10 08:22:46 gbvpi-bedroom go-librespot[2287]: time="2025-12-10T08:22:46-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:49 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:49 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 10 08:22:49 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:49 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:49 gbvpi-bedroom go-librespot[2294]: go-librespot daemon starting... Dec 10 08:22:49 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:49-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:49 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:49-05:00" level=debug msg="app state loaded" Dec 10 08:22:49 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:49-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=info msg="zeroconf server listening on port 37659" Dec 10 08:22:50 gbvpi-bedroom volumio[1197]: info: Discovery: Disconnected from remote: 192.168.1.28 Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="obtained new client token: AAD/eQIh02zipyiewJdksJDXqDwPHnIrxKzeceZbsdtLCWf8JVxihlZEcG+fcSMcEEnq+Dluz6fY6GfA4Y1dWdrOILccX/Ka14N4k2TLabsQ4kdeu4hGf6qCPUYDri4fxbngTIJYz98+kYnwVaM0/ao3yugf5dNU3sbBzL27d0D4PA1nxEfjtg+h9AmLboJAHFMhhcQ4/xpF0fbkGhml36qGZNcVe7OkldwxDpkf0bXa64QnkEifM7Q=" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=debug msg="completed challenge" Dec 10 08:22:50 gbvpi-bedroom go-librespot[2295]: time="2025-12-10T08:22:50-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:51 gbvpi-bedroom volumio[1197]: info: Discovery: Remote connection error: 192.168.1.28 Dec 10 08:22:51 gbvpi-bedroom volumio[1197]: info: Discovery: A device disappeared from network Dec 10 08:22:51 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:51 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:52 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:52 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:53 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 10 08:22:53 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:53 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:53 gbvpi-bedroom go-librespot[2316]: go-librespot daemon starting... Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="app state loaded" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=info msg="zeroconf server listening on port 37603" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="obtained new client token: AACFAIwvyhcQvZbFtglNT1qsMyq7NR2T5VmZlmUBKSaqlf7fHhFnZWWYqU5ic61+8VLj1NKptSSUTDgG+x840x4LsBBYrnJxw4wKSBEBK7gZyyn+aYrJMzez503ELICec8qSy/R8MDOjtEF/XCUmcNRAcynkNL/TcYByNuIDGMamS0vMxytCTHxc790lAInnjTKRnyrT64x9AACmUCs2OK7Nt/1auZlO/yxjkunNDxLjUNLGQ2NmR98saw==" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:53 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:53-05:00" level=debug msg="completed challenge" Dec 10 08:22:54 gbvpi-bedroom go-librespot[2317]: time="2025-12-10T08:22:54-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:54 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:54 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:54 gbvpi-bedroom volumio[1197]: info: Discovery: adding 2e705aa1-b77a-46e6-a814-faa1d0e00039 Dec 10 08:22:54 gbvpi-bedroom volumio[1197]: info: Discovery: Found device undefined Dec 10 08:22:54 gbvpi-bedroom volumio[1197]: info: Discovery: Connecting to remote: 192.168.1.28 Dec 10 08:22:55 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:55 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:22:56 gbvpi-bedroom volumio[1197]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 10 08:22:57 gbvpi-bedroom volumio[1197]: info: MRS: Pushing audio output on request Dec 10 08:22:57 gbvpi-bedroom volumio[1197]: info: MRS: Pushing multiroomSync output Dec 10 08:22:57 gbvpi-bedroom volumio[1197]: info: Discovery: Connected to remote: 192.168.1.28 Dec 10 08:22:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 10 08:22:57 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:57 gbvpi-bedroom go-librespot[2326]: go-librespot daemon starting... Dec 10 08:22:57 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="app state loaded" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:22:57 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=info msg="zeroconf server listening on port 33635" Dec 10 08:22:57 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="obtained new client token: AACHQ+xzPuE1gr5aa+kJ7q/OqrQ6plkpkGtRPiMjDexwe6jhs4G7dEKPkV177u5TVd0nvvOICWfcPq1QRiy0YTqBS0dV+Ouy/G5lB0fnwC2ZMBTOAo9sNdHTDTVywaL/qPSaLiVTAWpcF62fR07MQ0GYjfod25/xE2kOQHusxkJG+Y7dDoz/fFqeymDjI5Vj4ibXye5RtHFu3u4fPBzoLiaNkKo27LZTKvs5lmcuhHfCPU7JR9xDwA3uYQ==" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="completed keyexchange" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=debug msg="completed challenge" Dec 10 08:22:57 gbvpi-bedroom go-librespot[2327]: time="2025-12-10T08:22:57-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:22:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:22:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:22:58 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:22:58 gbvpi-bedroom volumio[1197]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:23:00 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 10 08:23:00 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:23:01 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:23:01 gbvpi-bedroom go-librespot[2337]: go-librespot daemon starting... Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="app state loaded" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Initializing connection to go-librespot Websocket Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="new websocket client" Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Connection to go-librespot Websocket established Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=info msg="zeroconf server listening on port 44317" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="obtained new client token: AABFI1WjYkQxw5Bugv2+Go6H+PRHkR4iymBNQNC3UX+2qga3WIhfP1rGN13CJaObWG08uwd5iEEznHJ+rUFFUaPPYrDZ475dwH1nrs1q0G7js3gqtIdu0Z3RGcduxBsWAIEWTgc5SotS/RoMzFR0bfKEgJk0lsb8x4VCvxsj1SZ2189onMmNX4x6g2RvkXvNvgTst1KOyX37UiZB1WJaHsXpJx7NZ/2Xe0m1pVnKCtyKxpRLxzz2FvEvaA==" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: CoreCommandRouter::volumioGetState Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="completed keyexchange" Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=debug msg="completed challenge" Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Listing playlists Dec 10 08:23:01 gbvpi-bedroom go-librespot[2338]: time="2025-12-10T08:23:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:23:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:23:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:23:01 gbvpi-bedroom volumio[1197]: info: Connection to go-librespot Websocket closed Dec 10 08:23:03 gbvpi-bedroom volumio[1197]: info: Discovery: Disconnected from remote: 192.168.1.28 Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: info: Getting Spotify volume Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: errno: -111, Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: code: 'ECONNREFUSED', Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: syscall: 'connect', Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: address: '127.0.0.1', Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: port: 9879, Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: response: undefined Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: } Dec 10 08:23:04 gbvpi-bedroom volumio[1197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 08:23:04 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 10 08:23:04 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:23:04 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 08:23:04 gbvpi-bedroom go-librespot[2372]: go-librespot daemon starting... Dec 10 08:23:04 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:04-05:00" level=info msg="running go-librespot 0.4.0" Dec 10 08:23:04 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:04-05:00" level=debug msg="app state loaded" Dec 10 08:23:04 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:04-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=info msg="zeroconf server listening on port 41833" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="obtained new client token: AACQfVFUqJ7GpVaroLpf86c2yhb/wilLSsdd8/63XMcLgBHLdL2fJ34n+ENMweG9CNHw0hCmobAl9VJqZ2v+v43/ZHoW8G9KvxCjCSjrPGdi63BVsDGKmOcisrScWblW9CI1oRVOUXZLPme5dQS5qDV+eUklptX2uyVJOnKL49I2oIN/S4ntYw02EPvKe13ZgLGoJgSGFPF5VQY8Cq+1Kjq9zxBBmoQxoF8Gr0UoHfWRA6ELaQuaUnk=" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="completed keyexchange" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=debug msg="completed challenge" Dec 10 08:23:05 gbvpi-bedroom go-librespot[2373]: time="2025-12-10T08:23:05-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 08:23:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 08:23:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 08:23:07 gbvpi-bedroom sudo[2383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 08:22' Dec 10 08:23:07 gbvpi-bedroom sudo[2383]: 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="6a0bffa825dc2af90b21170949f5219ba5ab5701" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 10 09:27:23 UTC 2025" VOLUMIO_VERSION="4.075" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bd01f93a654c7068f4553fbd69478e2"