Mar 04 00:19:00 volumio kernel: hwmon hwmon1: Voltage normalised Mar 04 00:19:00 volumio volumio[28455]: info: Loading plugin "squeezelite_mc"... Mar 04 00:19:01 volumio volumio[28455]: info: Loading plugin "youtube2"... Mar 04 00:19:02 volumio volumio[28455]: info: Loading plugin "ytcr"... Mar 04 00:19:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Mar 04 00:19:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:03 volumio go-librespot[28555]: go-librespot daemon starting... Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="app state loaded" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:03 volumio volumio[28455]: info: Loading plugin "ytmusic"... Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=info msg="zeroconf server listening on port 33537" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="obtained new client token: AACPbyCa2WzjtmrxT5xfRL+Cmln3PT5tZbnWF+6itrKpHEiJ+6nsJ7FNQ57D56Kz89JAEtoqhX+3UFKbU3GEnp6cIBWME7P4Mw7a/3s8xddPpipfgEUpFbdyEZTfK3f8CWkOC84hJsGeHpMz2VlWG/l3SkHufMUQjo6SL7sAlH5srr7I00xb5TPRaDPz9xhtYTjZDMsBT7LX/2xoBNL4QIyfp5StmegyKNlmFRDyiQtNCE6IZbpZatOKrA==" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=debug msg="completed challenge" Mar 04 00:19:03 volumio go-librespot[28556]: time="2026-03-04T00:19:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:03 volumio volumio[28455]: info: Plugin now_playing is not enabled Mar 04 00:19:03 volumio volumio[28455]: info: Loading plugin "outputs"... Mar 04 00:19:03 volumio volumio[28455]: info: Loading plugin "albumart"... Mar 04 00:19:04 volumio volumio[28455]: info: Plugin example_plugin is not enabled Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "inputs"... Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "updater_comm"... Mar 04 00:19:04 volumio volumio[28455]: info: Plugin mpdemulation is not enabled Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "rest_api"... Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "websocket"... Mar 04 00:19:04 volumio volumio[28455]: info: Starting Socket.io Server version 1.7.4 Mar 04 00:19:04 volumio volumio[28455]: info: Plugin fusiondsp is not enabled Mar 04 00:19:04 volumio volumio[28455]: info: Plugin mpdoutput is not enabled Mar 04 00:19:04 volumio volumio[28455]: info: Plugin RoonBridge is not enabled Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "podcast"... Mar 04 00:19:04 volumio volumio[28455]: info: ControllerPodcast::constructor Mar 04 00:19:04 volumio volumio[28455]: info: Loading plugin "volusonic"... Mar 04 00:19:04 volumio volumio[28563]: Forking 3 albumart workers Mar 04 00:19:05 volumio volumio[28455]: info: Applying required configuration parameters for plugin volusonic Mar 04 00:19:06 volumio volumio[28455]: info: Loading plugin "backup_restore"... Mar 04 00:19:06 volumio volumio[28589]: Starting albumart workers Mar 04 00:19:06 volumio volumio[28587]: Starting albumart workers Mar 04 00:19:06 volumio volumio[28588]: Starting albumart workers Mar 04 00:19:06 volumio volumio[28455]: info: Applying required configuration parameters for plugin backup_restore Mar 04 00:19:06 volumio volumio[28455]: info: Plugin rpi_eeprom_config is not enabled Mar 04 00:19:06 volumio volumio[28455]: info: Plugin rpi_eeprom_updater is not enabled Mar 04 00:19:06 volumio volumio[28455]: info: Loading plugin "scheduledrestart"... Mar 04 00:19:06 volumio volumio[28455]: info: Applying required configuration parameters for plugin scheduledrestart Mar 04 00:19:06 volumio volumio[28455]: info: Plugin Bluetoothremote is not enabled Mar 04 00:19:06 volumio volumio[28455]: info: Plugin music_services_shield is not enabled Mar 04 00:19:06 volumio volumio[28455]: info: Loading plugin "Systeminfo"... Mar 04 00:19:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Mar 04 00:19:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:06 volumio go-librespot[28627]: go-librespot daemon starting... Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="app state loaded" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:07 volumio volumio[28455]: info: Plugin peppymeterbasic is not enabled Mar 04 00:19:07 volumio volumio[28455]: info: Loading plugin "peppyspectrum"... Mar 04 00:19:07 volumio volumio-remote-updater[13697]: [2026-03-04 00:19:07] [connect] Successful connection Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=info msg="zeroconf server listening on port 40097" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="obtained new client token: AAAvSY8o9ygPOPrkH7/50wHUnQaxMcJ+l0y5JLPOVtBVDMKg2IBa7cqHkHO0mxRX1NprA5OVid0JkbqE+VICZY74PD7GxF2XHsOwQa3Odie/u9Vybem4kYbOIyvC6bw1V4DcOq3nAytH2c5UY6uDy/2ITRYTHqbSi7QalSyTCxH8idbLnGvV9+IguhqiIFQ9d8jsS5OWldwOJ0wOrG103Dem4TCl0HRLHxalHnDA3HSXX/4jZQu2MA7nzg==" Mar 04 00:19:07 volumio volumio[28455]: info: Loading i18n strings for locale en Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:07 volumio volumio[28455]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Mar 04 00:19:07 volumio volumio[28455]: Updating browse sources language Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=debug msg="completed challenge" Mar 04 00:19:07 volumio go-librespot[28633]: time="2026-03-04T00:19:07-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::initPlayerControls Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 00:19:07 volumio volumio[28455]: Express server listening on port 3000 Mar 04 00:19:07 volumio volumio[28455]: [Metrics] WebUI: 16s 242.63ms Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::resetVolumioState Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::getcurrentVolume Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:07 volumio sudo[28652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 00:19:07 volumio sudo[28652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:07 volumio sudo[28652]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:07 volumio sudo[28654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 00:19:07 volumio sudo[28654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:07 volumio sudo[28654]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:07 volumio volumio[28455]: info: Volumio Network Manager: Network status updated: 1 Mar 04 00:19:07 volumio volumio[28455]: info: VolumeController:: Volume=100 Mute =false Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::pushState Mar 04 00:19:07 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::volumioPushState Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::updateTrackBlock Mar 04 00:19:07 volumio volumio[28455]: info: CorePlayQueue::getTrackBlock Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 00:19:07 volumio volumio-remote-updater[13697]: [2026-03-04 00:19:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772605147 101 Mar 04 00:19:07 volumio volumio[28455]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 00:19:07 volumio volumio[28455]: info: Reloading queue from file Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::setRepeat null single undefined Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::pushState Mar 04 00:19:07 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::volumioPushState Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::setRandom null Mar 04 00:19:07 volumio volumio[28455]: info: CoreStateMachine::pushState Mar 04 00:19:07 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:07 volumio volumio[28455]: info: CoreCommandRouter::volumioPushState Mar 04 00:19:07 volumio volumio[28455]: info: Setting Device type: Raspberry PI Mar 04 00:19:08 volumio volumio[28455]: info: VolumeController:: Volume=100 Mute =false Mar 04 00:19:08 volumio volumio[28455]: info: CoreStateMachine::pushState Mar 04 00:19:08 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioPushState Mar 04 00:19:08 volumio volumio[28455]: info: Completed loading Core Plugins Mar 04 00:19:08 volumio sudo[28668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 04 00:19:08 volumio volumio[28455]: info: Preparing to generate the ALSA configuration file Mar 04 00:19:08 volumio sudo[28668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:08 volumio volumio[28455]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Mar 04 00:19:08 volumio volumio[28455]: info: Discovery: Found device Volumio Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioGetState Mar 04 00:19:08 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:08 volumio sudo[28668]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:08 volumio volumio[28455]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Mar 04 00:19:08 volumio volumio[28455]: info: Discovery: Found device Volumio Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioGetState Mar 04 00:19:08 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:08 volumio volumio[28455]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 04 00:19:08 volumio volumio[28455]: info: Reading ALSA contributions from plugins. Mar 04 00:19:08 volumio volumio[28455]: info: Upmpdcli Daemon Started Mar 04 00:19:08 volumio volumio[28455]: info: Asound.conf file unchanged, so no further update is needed Mar 04 00:19:08 volumio volumio[28455]: info: Output device has changed, restarting MPD Mar 04 00:19:08 volumio volumio[28455]: info: Output device has changed, restarting Shairport Sync Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:08 volumio sudo[28671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 00:19:08 volumio sudo[28671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:08 volumio sudo[28673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 00:19:08 volumio sudo[28673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:08 volumio sudo[28671]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:08 volumio volumio[28455]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 00:19:08 volumio volumio[28455]: info: ___________ START PLUGINS ___________ Mar 04 00:19:08 volumio volumio[28455]: info: ControllerMpd::onStart: Initializing MPD Mar 04 00:19:08 volumio volumio[28455]: info: Creating MPD Configuration file Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148230] CoreMusicLibrary::Adding element Media Servers Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 00:19:08 volumio sudo[28681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 00:19:08 volumio sudo[28681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:08 volumio sudo[28683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 00:19:08 volumio sudo[28683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:08 volumio sudo[28681]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:08 volumio volumio[28455]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:08 volumio volumio[28455]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 00:19:08 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 00:19:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 00:19:08 volumio systemd[1]: mpd.service: Consumed 4.100s CPU time. Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148357] CoreMusicLibrary::Adding element Last_100 Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148365] CoreMusicLibrary::Adding element Webradio Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 00:19:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 00:19:08 volumio volumio[28455]: info: Initializing BBC Radios Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148443] CoreMusicLibrary::Adding element Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148460] CoreMusicLibrary::Adding element SoundCloud Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: info: Creating Spotify config file Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:08 volumio sudo[28709]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 00:19:08 volumio sudo[28709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 00:19:08 volumio sudo[28709]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:08 volumio volumio[28455]: info: [squeezelite_mc] Starting proxy server... Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148828] CoreMusicLibrary::Adding element YouTube2 Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148850] CoreMusicLibrary::Adding element YouTube Music Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148863] CoreMusicLibrary::Adding element Podcast Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Podcast Mar 04 00:19:08 volumio volumio[28455]: info: Loading i18n strings for locale en Mar 04 00:19:08 volumio volumio[28455]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Mar 04 00:19:08 volumio volumio[28455]: Updating browse sources language Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Podcast Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Podcast Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:08 volumio volumio[28455]: info: [1772605148919] CoreMusicLibrary::Adding element Volusonic Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Podcast Mar 04 00:19:08 volumio volumio[28455]: Cannot find translation for source Volusonic Mar 04 00:19:08 volumio volumio[28455]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Mar 04 00:19:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 04 00:19:08 volumio volumio[28455]: info: Volumio Calling Home Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Proxy server started on port 43561 Mar 04 00:19:09 volumio volumio[28455]: info: Preparing to generate the ALSA configuration file Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 00:19:09 volumio volumio[28455]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 04 00:19:09 volumio volumio[28455]: info: Reading ALSA contributions from plugins. Mar 04 00:19:09 volumio volumio[28455]: info: MPD Permissions set Mar 04 00:19:09 volumio volumio[28455]: info: MPD Permissions set Mar 04 00:19:09 volumio volumio[28455]: info: Spotify config file written Mar 04 00:19:09 volumio sudo[28719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 04 00:19:09 volumio sudo[28719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:09 volumio volumio[28455]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: No need to fix Spotify hosts Mar 04 00:19:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:09 volumio go-librespot[28731]: go-librespot daemon starting... Mar 04 00:19:09 volumio sudo[28719]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:09 volumio volumio[28455]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 04 00:19:09 volumio volumio[28455]: info: VolumeController:: Volume=100 Mute =false Mar 04 00:19:09 volumio volumio[28455]: info: CoreStateMachine::pushState Mar 04 00:19:09 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::volumioPushState Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="app state loaded" Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Server discovery started Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Player finder started Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 04 00:19:09 volumio volumio[28455]: info: Volumio called home Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Mar 04 00:19:09 volumio volumio[28455]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Mar 04 00:19:09 volumio volumio[28455]: info: Starting Shairport Sync Mar 04 00:19:09 volumio volumio[28455]: info: Starting Shairport Sync Mar 04 00:19:09 volumio volumio[28455]: info: Starting Shairport Sync Mar 04 00:19:09 volumio sudo[28746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 00:19:09 volumio sudo[28746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:09 volumio sudo[28749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 00:19:09 volumio sudo[28749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:09 volumio sudo[28750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=info msg="zeroconf server listening on port 43325" Mar 04 00:19:09 volumio sudo[28750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 00:19:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 00:19:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:09 volumio systemd[1]: shairport-sync.service: Consumed 4.069s CPU time. Mar 04 00:19:09 volumio sudo[28744]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Mar 04 00:19:09 volumio sudo[28744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:09 volumio sudo[28744]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="obtained new client token: AABweQTm/rJeSJvYvwBVauGK+qPJFE6MDge8pbJ9/S7C81pEziXCSWXr/TBlrxf6z7PBEuZYYso2qbiJAHlGXGr69ugcJLbHJEQCQ8MwmQB3mGgMlYa3KT4KSmMoDetZotF0Xxh+n1boR2rlunWm9mQRuBCfblT/VJrb4CgP3dasszwKxQZRMYY6xNNbDTIzaw7f6sETpNOMnHM4K4jyNo3qHitwEAhH0Wf+Slt1ampCDmW39wdAEm3YfQ==" Mar 04 00:19:09 volumio sudo[28746]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 00:19:09 volumio go-librespot[28732]: time="2026-03-04T00:19:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 00:19:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:09 volumio volumio[28455]: info: Asound.conf file unchanged, so no further update is needed Mar 04 00:19:09 volumio volumio[28455]: info: Output device has changed, restarting MPD Mar 04 00:19:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:09 volumio sudo[28749]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio volumio[28455]: info: Output device has changed, restarting Shairport Sync Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:10 volumio sudo[28750]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio go-librespot[28732]: time="2026-03-04T00:19:10-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:10 volumio go-librespot[28732]: time="2026-03-04T00:19:10-06:00" level=debug msg="completed challenge" Mar 04 00:19:10 volumio sudo[28758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 00:19:10 volumio sudo[28758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio volumio[28455]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 00:19:10 volumio sudo[28758]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio go-librespot[28732]: time="2026-03-04T00:19:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:10 volumio sudo[28760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 00:19:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:10 volumio sudo[28760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 00:19:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 00:19:10 volumio systemd[1]: mpd.service: Consumed 1.569s CPU time. Mar 04 00:19:10 volumio volumio[28455]: info: Shairport-Sync Started Mar 04 00:19:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 00:19:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 00:19:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 00:19:10 volumio volumio[28455]: Error adding Membership: Error: addMembership EINVAL Mar 04 00:19:10 volumio volumio[28455]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Mar 04 00:19:10 volumio sudo[28788]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Mar 04 00:19:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 00:19:10 volumio sudo[28788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 00:19:10 volumio sudo[28788]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio volumio[28455]: info: MPD Permissions set Mar 04 00:19:10 volumio volumio[28455]: info: Shairport-Sync Started Mar 04 00:19:10 volumio volumio[28455]: info: Shairport-Sync Started Mar 04 00:19:10 volumio volumio[28455]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 04 00:19:10 volumio volumio[28455]: SPOTIFY: BQDlFj_zC8NdXLqSlFJf3s5dID7h1zrW5n3JRXpGu068DLowA_Y6aox3scn3GA8_-TunAerB-KaKQyvZ-nrDDGgz0uuS81ufVIBnBQgwfATr6Dx8EUDBz2irXT7MBOCxPj2OpNIzWRbd4mCKA8zl2hcmCiEZKfgayHYNgtiIfQxvT9q_vX044OLVY6Xq98vbWlsO3_zkgYmudjCVl6gMq1YOhKtMap6y2VETVKo Mar 04 00:19:10 volumio volumio[28455]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 04 00:19:10 volumio volumio[28455]: info: New Spotify access token = BQDlFj_zC8NdXLqSlFJf3s5dID7h1zrW5n3JRXpGu068DLowA_Y6aox3scn3GA8_-TunAerB-KaKQyvZ-nrDDGgz0uuS81ufVIBnBQgwfATr6Dx8EUDBz2irXT7MBOCxPj2OpNIzWRbd4mCKA8zl2hcmCiEZKfgayHYNgtiIfQxvT9q_vX044OLVY6Xq98vbWlsO3_zkgYmudjCVl6gMq1YOhKtMap6y2VETVKo Mar 04 00:19:10 volumio volumio[28455]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 04 00:19:10 volumio volumio[28455]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::volumioGetState Mar 04 00:19:10 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:10 volumio volumio[28455]: info: Starting Shairport Sync Mar 04 00:19:10 volumio sudo[28794]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Mar 04 00:19:10 volumio sudo[28794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio sudo[28794]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Mar 04 00:19:10 volumio sudo[28802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 00:19:10 volumio sudo[28802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio sudo[28790]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 00:19:10 volumio sudo[28790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 00:19:10 volumio sudo[28804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 04 00:19:10 volumio sudo[28804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:10 volumio sudo[28790]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:10 volumio volumio[28455]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Mar 04 00:19:10 volumio volumio[28455]: info: Spotify Successfully logged in Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 00:19:10 volumio volumio[28455]: info: [1772605150618] CoreMusicLibrary::Adding element Spotify Mar 04 00:19:10 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source Bandcamp Discover Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source SoundCloud Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source YouTube2 Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source YouTube Music Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source Podcast Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source Volusonic Mar 04 00:19:10 volumio volumio[28455]: Cannot find translation for source Spotify Mar 04 00:19:10 volumio systemd[1]: Reloading. Mar 04 00:19:11 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:11 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:11 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:11 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 00:19:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 00:19:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 00:19:12 volumio sudo[28804]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:12 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:12 volumio volumio[28455]: info: go-librespot daemon successfully initialized Mar 04 00:19:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 00:19:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 00:19:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:12 volumio systemd[1]: shairport-sync.service: Consumed 1.532s CPU time. Mar 04 00:19:12 volumio sudo[28827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:12 volumio sudo[28827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 00:19:12 volumio sudo[28802]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:12 volumio volumio[28455]: info: Shairport-Sync Started Mar 04 00:19:12 volumio sudo[28827]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:12 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Mar 04 00:19:12 volumio sudo[28844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Mar 04 00:19:12 volumio sudo[28844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:12 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Mar 04 00:19:12 volumio systemd[1]: squeezelite.service: Deactivated successfully. Mar 04 00:19:12 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Mar 04 00:19:12 volumio sudo[28844]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Mar 04 00:19:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:13 volumio go-librespot[28847]: go-librespot daemon starting... Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="app state loaded" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:13 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:13 volumio sudo[28856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:13 volumio sudo[28856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=info msg="zeroconf server listening on port 36199" Mar 04 00:19:13 volumio sudo[28856]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:13 volumio volumio[28455]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="obtained new client token: AACrlITD/M2Hv1t0DouMSvQD6xkgF4ZOL1vnZnMMyPqLldCKvS49bUROcTJaHg5bh+YXHykD2ZsXgfFe4loE2ZaHtJjVWoQ46TDflp9HPil/WIU+Wmbzt0KRQVI3sjpEmkUaG0td6sWoF3zIrZrNbweFpBhiFtvFcxUPUyvjNidb1onikdKdwTmVxHOWEOwQc/yAmMRc0ML4vG11Svpz/p4zcQRhTKsQJgBuBzY2MNjioJ2FaLMb6NUoyQ==" Mar 04 00:19:13 volumio sudo[28860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Mar 04 00:19:13 volumio sudo[28860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:13 volumio sudo[28860]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:13 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:13 volumio sudo[28863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Mar 04 00:19:13 volumio sudo[28863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=debug msg="completed challenge" Mar 04 00:19:13 volumio go-librespot[28848]: time="2026-03-04T00:19:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:13 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Mar 04 00:19:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:13 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Mar 04 00:19:13 volumio sudo[28863]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:14 volumio mpd[28808]: 2026-03-04T00:19:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 00:19:14 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:14 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 00:19:14 volumio sudo[28683]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:14 volumio sudo[28760]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:14 volumio sudo[28884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:14 volumio sudo[28673]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:14 volumio sudo[28884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:14 volumio sudo[28884]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:14 volumio volumio[28455]: error: MPD error: The expression evaluated to a falsy value: Mar 04 00:19:14 volumio volumio[28455]: assert.ok(self.idling) Mar 04 00:19:14 volumio volumio[28455]: error: The expression evaluated to a falsy value: Mar 04 00:19:14 volumio volumio[28455]: assert.ok(self.idling) Mar 04 00:19:14 volumio volumio[28455]: error: MPD error: The expression evaluated to a falsy value: Mar 04 00:19:14 volumio volumio[28455]: assert.ok(self.idling) Mar 04 00:19:14 volumio volumio[28455]: error: The expression evaluated to a falsy value: Mar 04 00:19:14 volumio volumio[28455]: assert.ok(self.idling) Mar 04 00:19:14 volumio volumio[28455]: info: MPD running with PID28808 Mar 04 00:19:14 volumio volumio[28455]: ,establishing connection Mar 04 00:19:14 volumio volumio[28455]: error: updateQueue error: null Mar 04 00:19:14 volumio volumio[28455]: error: updateQueue error: null Mar 04 00:19:15 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:15 volumio sudo[28891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:15 volumio sudo[28891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:15 volumio sudo[28891]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:15 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:15 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:15 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:15 volumio sudo[28894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:15 volumio sudo[28894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:15 volumio sudo[28894]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:16 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:16 volumio sudo[28897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:16 volumio sudo[28897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:16 volumio sudo[28897]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:16 volumio volumio[28455]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Mar 04 00:19:16 volumio sudo[28900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Mar 04 00:19:16 volumio sudo[28900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Mar 04 00:19:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:16 volumio go-librespot[28902]: go-librespot daemon starting... Mar 04 00:19:16 volumio sudo[28900]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:16 volumio go-librespot[28903]: time="2026-03-04T00:19:16-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:16 volumio go-librespot[28903]: time="2026-03-04T00:19:16-06:00" level=debug msg="app state loaded" Mar 04 00:19:16 volumio go-librespot[28903]: time="2026-03-04T00:19:16-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:16 volumio go-librespot[28903]: time="2026-03-04T00:19:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:17 volumio volumio[28455]: info: Completed starting Core Plugins Mar 04 00:19:17 volumio volumio[28455]: info: ------------------------------------------- Mar 04 00:19:17 volumio volumio[28455]: info: ----- MyVolumio plugins startup ---- Mar 04 00:19:17 volumio volumio[28455]: info: ------------------------------------------- Mar 04 00:19:17 volumio volumio[28455]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=info msg="zeroconf server listening on port 46245" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="obtained new client token: AABul6x26jgxaIbBwPDq/ZxTSVWgjuEm2yje/GSO5O808qtWDRbaruyN0TJUskCYTDX2AdUwHHzhUeOIoT0XaxS41zGZfREYBhVpu31aepJrCNAL9cS2mSC2JPIaNv366W1I8rA/RSTNpH1cQ2OvAoG8pwuwkokcSpmWNlWnsqEWANbcZ08EjuUKZO1YwCFIr8jVYXG2GBGZq8IdARMktJV/zVdwBrKlcL8pBioHlQpVjY+ZXeCKAL0/iA==" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=debug msg="completed challenge" Mar 04 00:19:17 volumio go-librespot[28903]: time="2026-03-04T00:19:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:17 volumio sudo[28911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 04 00:19:17 volumio sudo[28911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:17 volumio sudo[28913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 04 00:19:17 volumio sudo[28913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:17 volumio sudo[28915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 04 00:19:17 volumio sudo[28915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:17 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Mar 04 00:19:17 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Mar 04 00:19:17 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Mar 04 00:19:17 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Mar 04 00:19:17 volumio sudo[28915]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:17 volumio sudo[28911]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:17 volumio sudo[28913]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:17 volumio mpd_monitor.sh[28919]: MPD Monitor Service: Starting MPD Monitor Service Mar 04 00:19:17 volumio volumio[28455]: info: Successfully started MPD Monitor Mar 04 00:19:17 volumio volumio[28455]: info: Successfully started MPD Monitor Mar 04 00:19:17 volumio volumio[28455]: info: Successfully started MPD Monitor Mar 04 00:19:18 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:18 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Mar 04 00:19:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:20 volumio go-librespot[28923]: go-librespot daemon starting... Mar 04 00:19:20 volumio go-librespot[28924]: time="2026-03-04T00:19:20-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:20 volumio go-librespot[28924]: time="2026-03-04T00:19:20-06:00" level=debug msg="app state loaded" Mar 04 00:19:20 volumio go-librespot[28924]: time="2026-03-04T00:19:20-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:20 volumio go-librespot[28924]: time="2026-03-04T00:19:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=info msg="zeroconf server listening on port 45305" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="obtained new client token: AAANKk/BR0zS6yGKbiHI1Bbh+yYWdW8SSB7YOIpDaRujTsSk6MSo7d2/CjTfdREnZfgIqp8rhP1wyVqbQDv8UaiyzMCktTUGr5D4OQq/U4ProNMxz3vBE9Q58dTaNjzx3DIo0DItnrVGO5gx2sHb+cwSEEhvAutHKAaiu1kST/jmMpffhYgxAC5qcC5g++CH1iYLE81O9oVGDR2z4iUEroifasYVn1cNituLR1QLmGWGq9Bgxebll1E=" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=debug msg="completed challenge" Mar 04 00:19:21 volumio go-librespot[28924]: time="2026-03-04T00:19:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:22 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:22 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Mar 04 00:19:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:24 volumio go-librespot[28947]: go-librespot daemon starting... Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="app state loaded" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=info msg="zeroconf server listening on port 46619" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="obtained new client token: AACZXM7RgxoBCVkwLL+xJgkc8cP1SYLxLC5YM0wNxwI9K6wks+zNv+mevFdlay6syQ1ViYOr22YdxGXj5llAgsbIjPL41ImwKftcBFsQzzzKv4qWJUfJIHZaZJzegS0T6//bSVQxUy3Z+MRONGn8DEP9mPHUsWb+tu6+ZvHAh5xhunXGaZmZ7QN3sxzecPO15IkSXhGnzuSMbG+mJ6i6pnHZIQGum6JZzMeQ+Fjlf6xHef2yvuQW+Ygaog==" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=debug msg="completed challenge" Mar 04 00:19:24 volumio go-librespot[28948]: time="2026-03-04T00:19:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin bluetooth to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin multiroom to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin metavolumio to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin cd_controller to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 04 00:19:25 volumio volumio[28455]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 04 00:19:26 volumio volumio[28455]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 04 00:19:26 volumio volumio[28455]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 04 00:19:26 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:26 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:26 volumio volumio[28455]: info: Starting MyVolumio Remote Streaming Endpoints Mar 04 00:19:26 volumio volumio[28455]: info: MyVolumio login type: Token Mar 04 00:19:26 volumio volumio[28455]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 04 00:19:26 volumio volumio[28455]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 04 00:19:27 volumio volumio[28455]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 04 00:19:27 volumio volumio[28455]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 04 00:19:27 volumio volumio[28455]: info: Streaming services startup Mar 04 00:19:27 volumio volumio[28455]: info: Starting Streaming Daemon Mar 04 00:19:27 volumio sudo[28957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 00:19:27 volumio sudo[28957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:19:27 volumio volumio[28455]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 04 00:19:27 volumio sudo[28957]: pam_unix(sudo:session): session closed for user root Mar 04 00:19:27 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:27 volumio volumio[28455]: error: Cannot start Volumio Streaming Daemon Mar 04 00:19:27 volumio volumio[28455]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 04 00:19:27 volumio volumio[28455]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 04 00:19:27 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:27 volumio volumio[28455]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required. Mar 04 00:19:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Mar 04 00:19:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:28 volumio go-librespot[28963]: go-librespot daemon starting... Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="app state loaded" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=info msg="zeroconf server listening on port 41861" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="obtained new client token: AAAkKLZrjw0dPwNvMAPvAJFesyx1bIApb7g71DYyafmyuuZIi6jmVdHM2Hglv8Ajk4JUEOw1rae1gd3yWOH/TOrpDDSDWV+DgXWvyUeWX0zhjR1pQhnafP9TZ1WH7AqV9DlhzvEQjcXK05BKDUnsU3ePgzPrq9l34r9mVyq4KuBrDAvXySAaqImeRY/Q9PCfkePm40HhwBIiqeTwwwniU8yq7PjPQEZv8qfDoUjLWroVrKhOVYyjyWFIYg==" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=debug msg="completed challenge" Mar 04 00:19:28 volumio go-librespot[28964]: time="2026-03-04T00:19:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:30 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:30 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Mar 04 00:19:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:31 volumio go-librespot[28971]: go-librespot daemon starting... Mar 04 00:19:31 volumio go-librespot[28972]: time="2026-03-04T00:19:31-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:31 volumio go-librespot[28972]: time="2026-03-04T00:19:31-06:00" level=debug msg="app state loaded" Mar 04 00:19:31 volumio go-librespot[28972]: time="2026-03-04T00:19:31-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:31 volumio go-librespot[28972]: time="2026-03-04T00:19:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=info msg="zeroconf server listening on port 38135" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="obtained new client token: AACpqM/aCrVPXazetGdFZwkeWGNPQ7mljNI1XVTmOASrkT5+nNrytIuMDp8BJVlUBmJiG/fQqptxwrepn1k9L4tJ7w/gXKtAba9oFKKZBwZOaa5/5t4wHp8XfQku4ehUyE2SgonPdO38EyPpWoQPqzbeuo6TVly8a5PwvavGIOXuEasLiYigiQIeqr84Ix8lUvk5aJhcOcWA8Aq+KtJ4Mk3zLlwudCHG7FniHh4afZTCSioI1ihXfuXZ4Q==" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=debug msg="completed challenge" Mar 04 00:19:32 volumio go-librespot[28972]: time="2026-03-04T00:19:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:33 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:33 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Mar 04 00:19:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:35 volumio go-librespot[28993]: go-librespot daemon starting... Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=debug msg="app state loaded" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:35 volumio go-librespot[28994]: time="2026-03-04T00:19:35-06:00" level=info msg="zeroconf server listening on port 44887" Mar 04 00:19:36 volumio go-librespot[28994]: time="2026-03-04T00:19:36-06:00" level=debug msg="obtained new client token: AAAIAuuW3Rhxhzmx9iFrtU4/dBrhLzZOim3wKg1CyvWC2pgxw7qH1srvaLlS/kza07cJ/HlmPW4nmVsVjT4TLKQfXUHuZsx+xykTJ3hfo3iVaTl0RfgZSrLqYjBzOwqLqHiD+iCXcfPxpEWFpjEHm6Ot9WZctIBqNhFUDJmXz3to3I3XnsmIOHipELpbW8GKZ9xPzlOVs1Jem4lgRGJq47lxeNHwsOXNjmi6ppQVjuhxakFvlXGpxnc=" Mar 04 00:19:36 volumio go-librespot[28994]: time="2026-03-04T00:19:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:36 volumio go-librespot[28994]: time="2026-03-04T00:19:36-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:36 volumio go-librespot[28994]: time="2026-03-04T00:19:36-06:00" level=debug msg="completed challenge" Mar 04 00:19:36 volumio go-librespot[28994]: time="2026-03-04T00:19:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:36 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:36 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:38 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 00:19:38 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:38 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 00:19:38 volumio volumio-remote-updater[13697]: Test mode disabled Mar 04 00:19:38 volumio volumio-remote-updater[13697]: Alpha mode disabled Mar 04 00:19:38 volumio volumio-remote-updater[13697]: Alpha legacy test mode disabled Mar 04 00:19:38 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 04 00:19:39 volumio volumio[28455]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for CPU utilization spike
    • \n
  • Fix for triggerhappy input permission
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add BLE onboarding plugin
    • \n
  • Enable vc4-kms-v3d for all compatible RPi models
    • \n
  • Update Tidal SDK
    • \n
\n","title":"Update v4.096","updateavailable":true} Mar 04 00:19:39 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 04 00:19:39 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:39 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Mar 04 00:19:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:39 volumio go-librespot[29008]: go-librespot daemon starting... Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="app state loaded" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=info msg="zeroconf server listening on port 45007" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="obtained new client token: AAABZnayRDJr4L8L5oKpa3m5rDyj+HBFgFuISwyCRfIdMS50yBi2gAz/a4SO06h9zU3ZWett0hJ4m0Hghyz5awI9z5VJmw3Ri/dozjeGpn2GGH5YqTTZ7IKcbOuYgHiKNYzEasLEK0yeZmV4EKMatkTCRhsYq/SwXn60fTCZv/kKjVCXGRML24TMmap14pzbtbAWQuIjHGBjYIYceOb9offYdV00TXJmQDYMGo+EHzWuNVeUVMm+OzsuKg==" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=debug msg="completed challenge" Mar 04 00:19:39 volumio go-librespot[29009]: time="2026-03-04T00:19:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:41 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 00:19:41 volumio volumio[28455]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 04 00:19:41 volumio volumio[28455]: info: CoreCommandRouter::volumioGetState Mar 04 00:19:41 volumio volumio[28455]: info: CorePlayQueue::getTrack 0 Mar 04 00:19:42 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:42 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Mar 04 00:19:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:43 volumio go-librespot[29017]: go-librespot daemon starting... Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="app state loaded" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=info msg="zeroconf server listening on port 38379" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="obtained new client token: AACptXXjygXlC9bpRJa5e1LzoUb1661IComrP1oEg6FsF3MYSqzyYGb+N8niqOigMijkKvLHpvzgG/pZAwjDwdFO5wvRv43rqg0ao/QfTf7KjV4Yp5MWsPL4iCD5HJwx6kgD8D8p8lTq29adZicultnqzo10X4U/BaEgNnu8RpVqCmdgowPc0dckcEKC1/+2/vcutKHsv5QbMRtisvS/kFQz7VrbPgQ7JlMuuN6zRWJLV1W3IYJ96yvnhg==" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=debug msg="completed challenge" Mar 04 00:19:43 volumio go-librespot[29018]: time="2026-03-04T00:19:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:45 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:45 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 00:19:46 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 00:19:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Mar 04 00:19:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:46 volumio go-librespot[29040]: go-librespot daemon starting... Mar 04 00:19:46 volumio go-librespot[29041]: time="2026-03-04T00:19:46-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:46 volumio go-librespot[29041]: time="2026-03-04T00:19:46-06:00" level=debug msg="app state loaded" Mar 04 00:19:46 volumio go-librespot[29041]: time="2026-03-04T00:19:46-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:46 volumio go-librespot[29041]: time="2026-03-04T00:19:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:47 volumio volumio[28455]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 04 00:19:47 volumio volumio[28455]: info: Completed starting MyVolumio Plugin Mar 04 00:19:47 volumio volumio[28455]: [Metrics] CommandRouter: 55s 243.80ms Mar 04 00:19:47 volumio volumio[28455]: info: CoreCommandRouter::volumiosetStartupVolume Mar 04 00:19:47 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 00:19:47 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=info msg="zeroconf server listening on port 41183" Mar 04 00:19:47 volumio volumio[28455]: info: CoreCommandRouter::Close All Modals sent Mar 04 00:19:47 volumio volumio[28455]: info: CoreCommandRouter::Close All Modals sent Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="obtained new client token: AADaZMk08umB621tlcCFWqKmudvqfdsqg3YmgCGRYN5e0ZVCjy/xnkyxC5HiVTtZjc5ugGN+oawEQF8JDUaLjhuF+8jGimH2d6Zjt8iBWTdL0abW3TXwgMxyWOmlZ8w2yyEU+2BByi4Tx7L7cRel1+th0BhfAFVp1Jj+D6cgtWvsnXsIhVtzOFvohGcnzVSAqWoE6pKEryxFePkmD77o/c9YVsAD+f4tiUPg2GPklp+YmgTpavMxErM=" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=debug msg="completed challenge" Mar 04 00:19:47 volumio go-librespot[29041]: time="2026-03-04T00:19:47-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:48 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 04 00:19:48 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 00:19:48 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 04 00:19:48 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:48 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Mar 04 00:19:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:50 volumio go-librespot[29054]: go-librespot daemon starting... Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=debug msg="app state loaded" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 04 00:19:50 volumio go-librespot[29055]: time="2026-03-04T00:19:50-06:00" level=info msg="zeroconf server listening on port 33071" Mar 04 00:19:51 volumio go-librespot[29055]: time="2026-03-04T00:19:51-06:00" level=debug msg="obtained new client token: AADwDPwBRS1PxtnezKU1qYeDQBnTPT+rh0JYr1lFCzVBCVX4leUxXbUfSrSFl5xG/oNBFCHqDplWNlxIGJaZxGzYJ2OzOA2JbMfksVPnzSB4hcP+DST5CKkDSjjm0L3XusYOTO9igsjy2S4unq6QBdI2O9ZDwnzyazyDZ+38pltfYaFuwSL3DIah7HtaQNTDWaSdetXgs2sFL9KRCJgWVf9/9s+Yb2nRE6zb4kYeHARlDfpuoWIrRhk=" Mar 04 00:19:51 volumio go-librespot[29055]: time="2026-03-04T00:19:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:51 volumio go-librespot[29055]: time="2026-03-04T00:19:51-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:51 volumio go-librespot[29055]: time="2026-03-04T00:19:51-06:00" level=debug msg="completed challenge" Mar 04 00:19:51 volumio go-librespot[29055]: time="2026-03-04T00:19:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:51 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:51 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:54 volumio volumio[28455]: info: BOOT COMPLETED Mar 04 00:19:54 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:54 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Mar 04 00:19:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:54 volumio go-librespot[29076]: go-librespot daemon starting... Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="app state loaded" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=info msg="zeroconf server listening on port 35853" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="obtained new client token: AABOakheLHWajkWPXcTvkLZPWU/qpKdD9qMXy4fz2HIvS8pqrXntqv27tU2oek9oUFJKwV95scTa+Ctb4F7vxnalgCuPcaURRdwysXJiPn9R469OBg4vRpeXoYAMzQBvaqdXWKRvSZ8eb8eXFw/EYKtjpgvD+/RcrvlrAuVHlaiXLRtJOahWmoZQTCXnphMzJDTmyI3C3kQZkfgdwobWWNvF0OieOwLHkGSbEbTaK8FXwsEJXvizRB48SQ==" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=debug msg="completed challenge" Mar 04 00:19:54 volumio go-librespot[29077]: time="2026-03-04T00:19:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:19:57 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:19:57 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:19:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Mar 04 00:19:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:19:58 volumio go-librespot[29088]: go-librespot daemon starting... Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="app state loaded" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="stored credentials not found" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=info msg="zeroconf server listening on port 32833" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="obtained new client token: AAAdWlee3EKoRpOGB39aMghR146UMQWANbOirqGErZ51zf6e48wdDtK55uaj3bGeiO9jts8Z90CYKNGmF0CUojB7Wd3bcFm9orougbnUiAG9g8iBDlORwiNYNSxPplN/T3XMs7BPszaYGZ1xiBxdZ5h8G2dIHgn0c3G9231JHlsU+3o45YZjPxd6DtPO7cXBIWCwirvzw8rTEvkxkiJ4AjZa+MB79FIBoutqhyU2UM9Fg4v9/qHs6+6oug==" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="completed keyexchange" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=debug msg="completed challenge" Mar 04 00:19:58 volumio go-librespot[29089]: time="2026-03-04T00:19:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:19:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:19:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:00 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:00 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Mar 04 00:20:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:01 volumio go-librespot[29097]: go-librespot daemon starting... Mar 04 00:20:01 volumio go-librespot[29098]: time="2026-03-04T00:20:01-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:01 volumio go-librespot[29098]: time="2026-03-04T00:20:01-06:00" level=debug msg="app state loaded" Mar 04 00:20:01 volumio go-librespot[29098]: time="2026-03-04T00:20:01-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:01 volumio go-librespot[29098]: time="2026-03-04T00:20:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=info msg="zeroconf server listening on port 35629" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="obtained new client token: AAAQwBAvGdGav6RtHSToWnqQ6QAjxjRdLc+oQtn3Q/Hb9vQEARdZZEr7UA1fR4GFuADzvCy7lvAODA678WUZB6ytGJCURq8OCNYEH9NqJZ/TM5dYCr1QoEGwJ1A5HEvJm4+9cfIWUFjkN/aexEfFER5TFyMI+LM92ftrg8/TLPC50KzemvAOv991ZNXUF+9yevNGIE+ie3e6mhqKm6N98aAA+AAnfNn4dP8FsTSxIQH36oBWF6D7sMdSdQ==" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=debug msg="completed challenge" Mar 04 00:20:02 volumio go-librespot[29098]: time="2026-03-04T00:20:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:03 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:03 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Mar 04 00:20:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:05 volumio go-librespot[29119]: go-librespot daemon starting... Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=debug msg="app state loaded" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:05 volumio go-librespot[29120]: time="2026-03-04T00:20:05-06:00" level=info msg="zeroconf server listening on port 45171" Mar 04 00:20:06 volumio go-librespot[29120]: time="2026-03-04T00:20:06-06:00" level=debug msg="obtained new client token: AAAlAWIQe9wJNO25FyFXz2OqYfQb4gSeo4Pfu5FRLhDIBPCysLPN/+L4zltWQyhpAM3kCDQqzyJGj4gjLt6Qh/gXJioldgLZ5A78BN2bGNkTIH6e6GDCr9kdVPIybKUh8PisDecg4COLTykLxdyFM+zjKLUnLkttkEjeAtUu4mT9wBr+NcAR+qJfAanPFfQiEy3WlOSTDnggCgotkzn40mxM9S4yka13MaCeBwUivco+Y6T2qhkriwI=" Mar 04 00:20:06 volumio go-librespot[29120]: time="2026-03-04T00:20:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:06 volumio go-librespot[29120]: time="2026-03-04T00:20:06-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:06 volumio go-librespot[29120]: time="2026-03-04T00:20:06-06:00" level=debug msg="completed challenge" Mar 04 00:20:06 volumio go-librespot[29120]: time="2026-03-04T00:20:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:06 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:06 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:08 volumio volumio[28455]: info: UPDATER: Scheduling automatic update Mar 04 00:20:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Mar 04 00:20:08 volumio volumio[28455]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Mar 04 00:20:08 volumio volumio[28455]: info: UPDATER: Auto update will take place at: Thu Mar 05 2026 03:42:52 GMT-0600 (Central Standard Time) Mar 04 00:20:09 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:09 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Mar 04 00:20:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:09 volumio go-librespot[29127]: go-librespot daemon starting... Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="app state loaded" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=info msg="zeroconf server listening on port 33153" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="obtained new client token: AAAm7z5KHc15QBH9DBtPoBFh5NAoBKxAu7zuihgZqIf8RuWypLsYhRzJnC3A2SvsIrAhKYE53sEYTdzxTWAYK/8qClha5287/Oct0hoWSb54SDFZj6kF3N9w/bVaK2NHctRuGpxZtQj//PT5ipZwCQv5wMlNj3x+/wvKkk9PUPZdE0pyY2O7BCAfiwIdGr4ElclS/rjy4MvUk81zbcagx552hZgWx447YDriHbs+r1KTLdsFxxk89HqCag==" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=debug msg="completed challenge" Mar 04 00:20:09 volumio go-librespot[29128]: time="2026-03-04T00:20:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:12 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:12 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Mar 04 00:20:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:13 volumio go-librespot[29136]: go-librespot daemon starting... Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="app state loaded" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=info msg="zeroconf server listening on port 35203" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="obtained new client token: AADHfSAm/xIVcwIqWs01v3oEyZZLBSjnpeecdjMGrfqlwCwosnyqDsLNYhN63da0ejLY2eO/qkZ9zAbxdEjVZ2U6S6xEEqBnFO27x3ND4Hh2b4JEXu9a+d3gHnXQlXQJsHPfGyyK3HPyun62EMhfW8JyFBILizmhEVDmWfkMWgbfP33L+stqOxdJMD/Q+dMoCkJMMvsEn2zRQDBad37Y2xh4BTs3IlBNwRYfLmHepDx7HL58RzGF1dNAZA==" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=debug msg="completed challenge" Mar 04 00:20:13 volumio go-librespot[29137]: time="2026-03-04T00:20:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:15 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:15 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Mar 04 00:20:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:16 volumio go-librespot[29159]: go-librespot daemon starting... Mar 04 00:20:16 volumio go-librespot[29160]: time="2026-03-04T00:20:16-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:16 volumio go-librespot[29160]: time="2026-03-04T00:20:16-06:00" level=debug msg="app state loaded" Mar 04 00:20:16 volumio go-librespot[29160]: time="2026-03-04T00:20:16-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:16 volumio go-librespot[29160]: time="2026-03-04T00:20:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=info msg="zeroconf server listening on port 44713" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="obtained new client token: AAAFPPvDnjJllpdVReNwpi1xTYQ4YqWi7WbPBcfnZYJn9gVWC4/YSvt0SoKfxyk6CrddLcuucUf/eFtV8TVN6jEjJHxfAf8CDW67WPK4ya5s4R1zuFqV1bG0Or6JuIWwJjllIZ/Fe1YTAqWQb1ZkrQ8z3V3Rg2S6+jNpftVruzVI24+V+TRIUcILjV1EkVIuBB76qj4+esyrl089uXxUKodPq8Ahmw+ZYKQit3LS7RhDCQv4pjPDi6f5ow==" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=debug msg="completed challenge" Mar 04 00:20:17 volumio go-librespot[29160]: time="2026-03-04T00:20:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:18 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:18 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Mar 04 00:20:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:20 volumio go-librespot[29170]: go-librespot daemon starting... Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=debug msg="app state loaded" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:20 volumio go-librespot[29171]: time="2026-03-04T00:20:20-06:00" level=info msg="zeroconf server listening on port 38071" Mar 04 00:20:21 volumio go-librespot[29171]: time="2026-03-04T00:20:21-06:00" level=debug msg="obtained new client token: AAAgHn66kn6jNHY9SDV3FlUcdVMHf25q/bR8WXlF8eKv3uEMs19fZ8pulBu8BoNrm1CmWITSkSED7OU96huZwfFzvV/KJ8Qe05EfkcekFKirZY2e8JgDCFcIWXdKGWVvvw1beNg8ULo2px0ccwJZkeFIh90YJWxV/X7JiierS+GnSMWzijvDheRrK7IqwD8QbXUV14+nOctkhLKluA822RZiQsn+ws4Hm3kDKBSOpWhPBuFLwHfv3GI=" Mar 04 00:20:21 volumio go-librespot[29171]: time="2026-03-04T00:20:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:21 volumio go-librespot[29171]: time="2026-03-04T00:20:21-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:21 volumio go-librespot[29171]: time="2026-03-04T00:20:21-06:00" level=debug msg="completed challenge" Mar 04 00:20:21 volumio go-librespot[29171]: time="2026-03-04T00:20:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:21 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:21 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:24 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Mar 04 00:20:24 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:24 volumio go-librespot[29186]: go-librespot daemon starting... Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="app state loaded" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=info msg="zeroconf server listening on port 42987" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="obtained new client token: AADo/oTD8+ZAR1VWd7LXzRD7ZRayMhQdRg0Q5qlwkjqy2hFGPplwj7nyzyoU3R+fmGHB+An9n9d5Cdw/6vDB7D9Q8J4BbgY/hvVD5YBWowVn+NK2F8014CIxY0iLcvbbxh27PvZ1HpXF8BdnyISy5tghllYN3xKJzrG5lvd7Qvwv/M5SpE66zL0I9mxxNrx+xaDOSjEg3TCDbT+mw2LqVXsiUlzkzhdhw01Vdm2s4QYjzvrhLrCbiPOKAQ==" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=debug msg="completed challenge" Mar 04 00:20:24 volumio go-librespot[29193]: time="2026-03-04T00:20:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:27 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:27 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Mar 04 00:20:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:28 volumio go-librespot[29200]: go-librespot daemon starting... Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="app state loaded" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=info msg="zeroconf server listening on port 37849" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="obtained new client token: AAAO4IB9pTDa8QjV8e1u8b9SazUbizsYaGo17fFSkpXeam8XiXAERd7D6UvLwgrtzcs2xnvJhbrAxdMJyQv/W38i0gUwdXxOYLRO+IRZPVVnZ62HYAS7N+Toa16Oh34RclIMLKV3oTSXF+BWpG72fNuiF1u8tq81Ey9tZmKJDKuqULQ8sR5xlInlNCi+d+FPPSmzpRW3KKPcbTtdyX6BbdLXue7g01cWVxbw+Q2knLFyTujcj8OEDVcdYA==" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=debug msg="completed challenge" Mar 04 00:20:28 volumio go-librespot[29201]: time="2026-03-04T00:20:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:30 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:30 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Mar 04 00:20:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:31 volumio go-librespot[29208]: go-librespot daemon starting... Mar 04 00:20:31 volumio go-librespot[29209]: time="2026-03-04T00:20:31-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:31 volumio go-librespot[29209]: time="2026-03-04T00:20:31-06:00" level=debug msg="app state loaded" Mar 04 00:20:31 volumio go-librespot[29209]: time="2026-03-04T00:20:31-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:31 volumio go-librespot[29209]: time="2026-03-04T00:20:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=info msg="zeroconf server listening on port 38877" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="obtained new client token: AAA8oQAFTG1wY0TMx+iTFYvBs9zmcW/AtiS5S/dLniSWV9LnxnLNZikdLJ4DGdPQPDe2IFqaUjUmwk91E032YvKBBdYEHcUvSKddD/j74HnB+7lMWrlaA+EeJ4qq2nSky+TO0OxZ1KxwgbUl7cSGeTrzH4EgrSfVwLF3AraaG21r7DHFex+UxHPIsDRMklJxnizxxA4AvYURl+1k8mFmvraIaWj3QaH7YtVkQzZBVD+Y9+YYCeRQkRU=" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=debug msg="completed challenge" Mar 04 00:20:32 volumio go-librespot[29209]: time="2026-03-04T00:20:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:33 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:33 volumio volumio[28455]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Mar 04 00:20:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:36 volumio go-librespot[29230]: go-librespot daemon starting... Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="app state loaded" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:36 volumio volumio[28455]: info: Initializing connection to go-librespot Websocket Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="new websocket client" Mar 04 00:20:36 volumio volumio[28455]: info: Connection to go-librespot Websocket established Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=info msg="zeroconf server listening on port 45663" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="obtained new client token: AABxKaalPSDwvSphXBQGqmNUYn+/IAOe8ZhOVB8jghGltBGspSWoaZT6YNDOUOtUBNJN0L7dlvhJfPDi/vEIL9KPpiNUdmmAyxfo1Ar6UUaZLvouoAQZoT3YL9rgNG3uqnBwWYwHOhCjXNApXGWrk/NsByXJPmFOOljQBTVVvBQboYAtBtoyPLwX9KZmMBjGMud1IF4hFvVTr7cx6Fjjc1PZ2pSYA5+4RcTBAFKhgOYnUB/PFCBgOxiCyw==" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="completed keyexchange" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=debug msg="completed challenge" Mar 04 00:20:36 volumio go-librespot[29231]: time="2026-03-04T00:20:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 00:20:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 00:20:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 00:20:36 volumio volumio[28455]: info: Connection to go-librespot Websocket closed Mar 04 00:20:39 volumio volumio[28455]: info: Getting Spotify volume Mar 04 00:20:39 volumio volumio[28455]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 00:20:39 volumio volumio[28455]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 00:20:39 volumio volumio[28455]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 04 00:20:39 volumio volumio[28455]: errno: -111, Mar 04 00:20:39 volumio volumio[28455]: code: 'ECONNREFUSED', Mar 04 00:20:39 volumio volumio[28455]: syscall: 'connect', Mar 04 00:20:39 volumio volumio[28455]: address: '127.0.0.1', Mar 04 00:20:39 volumio volumio[28455]: port: 9879, Mar 04 00:20:39 volumio volumio[28455]: response: undefined Mar 04 00:20:39 volumio volumio[28455]: } Mar 04 00:20:39 volumio volumio[28455]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 00:20:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Mar 04 00:20:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 00:20:39 volumio go-librespot[29253]: go-librespot daemon starting... Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=info msg="running go-librespot 0.4.0" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="app state loaded" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="stored credentials not found" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 04 00:20:40 volumio sudo[29263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 00:19' Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=info msg="zeroconf server listening on port 41671" Mar 04 00:20:40 volumio sudo[29263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="obtained new client token: AABYmxEPWdtm35c8HrBGKtOw1QxpDyJiczVflLIgLtu1d0ysZxN0AjCLiMn8t72Hc/F41ziYHD9JtbrbqB3mX/6V5gF3L0PhcNBBsGmRmSewOQxRYlNaTxlGHebAh2NSrmGotIMzJ9Tdbl9iQvRbkRLTCtHCG7E8lcXaG9B2exRVgGiifxIgpz+Fh2Mp54rnqKRoWLM06HEiQ+ea5ZdKzXkVikX8mMX3x6OUIuz2lebzlzxjXLTR+jqiUw==" Mar 04 00:20:40 volumio go-librespot[29254]: time="2026-03-04T00:20:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"