Feb 23 15:49:00 volumio volumio[31657]: info: Loading plugin "network"... Feb 23 15:49:00 volumio volumio[31657]: info: Refreshing Cached IP Addresses Feb 23 15:49:00 volumio sudo[31695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 15:49:00 volumio sudo[31695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:00 volumio sudo[31697]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 15:49:00 volumio sudo[31697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:00 volumio volumio[31657]: info: Loading plugin "services"... Feb 23 15:49:00 volumio sudo[31695]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:00 volumio sudo[31697]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:00 volumio volumio[31657]: info: Loading plugin "alsa_controller"... Feb 23 15:49:00 volumio sudo[31705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 15:49:00 volumio sudo[31705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:01 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "wizard"... Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "networkfs"... Feb 23 15:49:01 volumio volumio[31657]: info: Starting Udev Watcher for removable devices Feb 23 15:49:01 volumio volumio[31657]: info: Ignoring mount for partition: boot Feb 23 15:49:01 volumio volumio[31657]: info: Ignoring mount for partition: volumio Feb 23 15:49:01 volumio volumio[31657]: info: Ignoring mount for partition: volumio_data Feb 23 15:49:01 volumio volumio[31657]: info: Mounting Device Wikipedia Feb 23 15:49:01 volumio sudo[31734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 23 15:49:01 volumio sudo[31734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:01 volumio sudo[31734]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:01 volumio volumio[31657]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 23 15:49:01 volumio volumio[31657]: dmesg(1) may have more information after failed mount system call. Feb 23 15:49:01 volumio volumio[31657]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 23 15:49:01 volumio volumio[31657]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 23 15:49:01 volumio volumio[31657]: dmesg(1) may have more information after failed mount system call. Feb 23 15:49:01 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "volumio_command_line_client"... Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "upnp"... Feb 23 15:49:01 volumio volumio[31657]: info: [1771883341334] Starting Upmpd Daemon Feb 23 15:49:01 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "my_music"... Feb 23 15:49:01 volumio volumio[31657]: info: Loading plugin "mpd"... Feb 23 15:49:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 23 15:49:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:01 volumio go-librespot[31736]: go-librespot daemon starting... Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-06:00" level=debug msg="app state loaded" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:01 volumio sudo[31705]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:01 volumio volumio-remote-updater[26837]: [2026-02-23 15:49:01] [connect] Successful connection Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-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]" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-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]" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-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]" Feb 23 15:49:01 volumio go-librespot[31737]: time="2026-02-23T15:49:01-06:00" level=info msg="zeroconf server listening on port 35447" Feb 23 15:49:02 volumio volumio[31657]: info: Loading plugin "upnp_browser"... Feb 23 15:49:02 volumio go-librespot[31737]: time="2026-02-23T15:49:02-06:00" level=debug msg="obtained new client token: AACF4tVwui1x2RrnPg2Qzgs0ELi9tsLmxGiPSKVPpY5zwW96RaqmBKWF9JgIP8NNTe0B8+0mPUU1qKHgQ+iPYOTm2lkKbEJ3DWrwN9qT5WOib43VHQdohk68FwuTIPTX9auRC3G7fsZJT9pbmLDL8lHWsDQYLbf0oAkxA1j5I723hFwAaO4Kk97u5fIuepH+xiAWHpUDpgz3Dj4ZzdAOVHomY6ng+OKvemgWURJnyY9TZy7Y00naexE=" Feb 23 15:49:02 volumio go-librespot[31737]: time="2026-02-23T15:49:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:02 volumio go-librespot[31737]: time="2026-02-23T15:49:02-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:02 volumio go-librespot[31737]: time="2026-02-23T15:49:02-06:00" level=debug msg="completed challenge" Feb 23 15:49:02 volumio go-librespot[31737]: time="2026-02-23T15:49: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 " Feb 23 15:49:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:02 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "alarm-clock"... Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "airplay_emulation"... Feb 23 15:49:04 volumio volumio[31657]: info: Starting Shairport Sync Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "last_100"... Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "webradio"... Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "i2s_dacs"... Feb 23 15:49:04 volumio volumio[31657]: info: I2S DAC not set, start Auto-detection Feb 23 15:49:04 volumio volumio[31657]: info: Loading plugin "volumiodiscovery"... Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** For more information see Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 15:49:04 volumio volumio[31657]: *** WARNING *** For more information see Feb 23 15:49:04 volumio node[31657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 15:49:04 volumio node[31657]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 15:49:04 volumio node[31657]: *** WARNING *** For more information see Feb 23 15:49:04 volumio node[31657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 15:49:04 volumio node[31657]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 15:49:04 volumio node[31657]: *** WARNING *** For more information see Feb 23 15:49:04 volumio volumio[31657]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 15:49:04 volumio volumio[31657]: info: Discovery: Started advertising with name: Volumio Feb 23 15:49:05 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 15:49:05 volumio volumio[31657]: info: Loading plugin "bandcamp"... Feb 23 15:49:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 23 15:49:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:05 volumio go-librespot[31748]: go-librespot daemon starting... Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="app state loaded" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-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]" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-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]" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=info msg="zeroconf server listening on port 36539" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="obtained new client token: AAAA941JAU+nOVeWxa/3jiS2jAbaKpCcTYYc77CqFSgyQ+B/VNWOFVnHgg5TEk37PWzTkYT0yM+0Hun1lPwXftnbn1C0wxS+sKHOjs59LbLtmL0PL+0Ueq8xJzZFrV76xP+4iIoR+IUrP54IfCIWy6tRPDfP/iAV1arr/SbLoD9x+YLMV2cam985lPLhHMIWpkww4tzD6meStCaZzUVWU9In5BYMx2EbRlYN4Pn5IydWlqNqkrTEmOYQCw==" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:05 volumio go-librespot[31749]: time="2026-02-23T15:49:05-06:00" level=debug msg="completed challenge" Feb 23 15:49:06 volumio go-librespot[31749]: time="2026-02-23T15:49: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 " Feb 23 15:49:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:06 volumio volumio[31657]: info: Plugin calmradio is not enabled Feb 23 15:49:06 volumio volumio[31657]: info: Loading plugin "soundcloud"... Feb 23 15:49:06 volumio volumio[31657]: info: Loading plugin "spop"... Feb 23 15:49:06 volumio kernel: hwmon hwmon1: Voltage normalised Feb 23 15:49:07 volumio volumio[31657]: info: Loading plugin "squeezelite_mc"... Feb 23 15:49:08 volumio volumio[31657]: info: Loading plugin "youtube2"... Feb 23 15:49:08 volumio volumio[31657]: info: Loading plugin "ytcr"... Feb 23 15:49:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 23 15:49:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:09 volumio go-librespot[31759]: go-librespot daemon starting... Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="app state loaded" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49: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]" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49: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]" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49: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]" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=info msg="zeroconf server listening on port 40601" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="obtained new client token: AAAmqGLDi8+kBjbD++z26yJ0gvVlOqnJk0DNQvzY0+EToVZUwRF9eQSE5UjVqzzNPTMa3eyyKZ5WOF/xbhDGgX5IQ0HicXQSQ83fDXFTLH5cXcSRWWQHOMn8XAhcyHyxO1de+s3+HmAEZhMr4E0t1VPfiF4PM5Q5r3400o7c59/T1QPFIKh8sEJGBfGJGkQpXO0t3Zb1o2G2DJNqISMsg01AT6f+E5g7DrH8+5LYX+ySpNwFQIpUsd4C7A==" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49:09-06:00" level=debug msg="completed challenge" Feb 23 15:49:09 volumio go-librespot[31760]: time="2026-02-23T15:49: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 " Feb 23 15:49:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:10 volumio volumio[31657]: info: Loading plugin "ytmusic"... Feb 23 15:49:10 volumio volumio[31657]: info: Plugin now_playing is not enabled Feb 23 15:49:10 volumio volumio[31657]: info: Loading plugin "outputs"... Feb 23 15:49:10 volumio volumio[31657]: info: Loading plugin "albumart"... Feb 23 15:49:10 volumio volumio[31657]: info: Plugin example_plugin is not enabled Feb 23 15:49:10 volumio volumio[31657]: info: Loading plugin "inputs"... Feb 23 15:49:10 volumio volumio[31657]: info: Loading plugin "updater_comm"... Feb 23 15:49:11 volumio volumio[31657]: info: Plugin mpdemulation is not enabled Feb 23 15:49:11 volumio volumio[31657]: info: Loading plugin "rest_api"... Feb 23 15:49:11 volumio volumio[31657]: info: Loading plugin "websocket"... Feb 23 15:49:11 volumio volumio[31657]: info: Starting Socket.io Server version 1.7.4 Feb 23 15:49:11 volumio volumio[31657]: info: Plugin fusiondsp is not enabled Feb 23 15:49:11 volumio volumio[31657]: info: Plugin mpdoutput is not enabled Feb 23 15:49:11 volumio volumio[31657]: info: Plugin RoonBridge is not enabled Feb 23 15:49:11 volumio volumio[31657]: info: Loading plugin "podcast"... Feb 23 15:49:11 volumio volumio[31657]: info: ControllerPodcast::constructor Feb 23 15:49:11 volumio volumio[31657]: info: Loading plugin "volusonic"... Feb 23 15:49:11 volumio volumio[31772]: Forking 3 albumart workers Feb 23 15:49:12 volumio volumio[31657]: info: Applying required configuration parameters for plugin volusonic Feb 23 15:49:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 23 15:49:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:12 volumio volumio[31657]: info: Loading plugin "backup_restore"... Feb 23 15:49:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:12 volumio go-librespot[31826]: go-librespot daemon starting... Feb 23 15:49:13 volumio volumio[31796]: Starting albumart workers Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="app state loaded" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:13 volumio volumio[31797]: Starting albumart workers Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:13 volumio volumio[31798]: Starting albumart workers Feb 23 15:49:13 volumio volumio[31657]: info: Applying required configuration parameters for plugin backup_restore Feb 23 15:49:13 volumio volumio[31657]: info: Plugin rpi_eeprom_config is not enabled Feb 23 15:49:13 volumio volumio[31657]: info: Plugin rpi_eeprom_updater is not enabled Feb 23 15:49:13 volumio volumio[31657]: info: Loading plugin "scheduledrestart"... Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49: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]" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49: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]" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49: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]" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=info msg="zeroconf server listening on port 33305" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="obtained new client token: AABpZJrIpAvAzlEDH9Uq1p5EFWsvLCSUeF+RKPhC9nnOku3M7tyrdmzM5WAk1mu1VM0/UYuaWF8hHZ5koPWdHBXCgdectgDo8/Xecx1WyqaPRmLvFgm+QEybsinEY00DYYHjIB3U14RovEo5nprLp5s9StncDj+R56Slbxn0PB7vUzVZG0mtpN6EmoiQERbm08yhH7VZV0ZvUgKh5PBwZGuZH1EivVBmltweANM5nSyIO63e6fD85dEmgA==" Feb 23 15:49:13 volumio volumio[31657]: info: Applying required configuration parameters for plugin scheduledrestart Feb 23 15:49:13 volumio volumio[31657]: info: Plugin Bluetoothremote is not enabled Feb 23 15:49:13 volumio volumio[31657]: info: Plugin music_services_shield is not enabled Feb 23 15:49:13 volumio volumio[31657]: info: Loading plugin "Systeminfo"... Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49:13-06:00" level=debug msg="completed challenge" Feb 23 15:49:13 volumio go-librespot[31827]: time="2026-02-23T15:49: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 " Feb 23 15:49:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:14 volumio volumio[31657]: info: Plugin peppymeterbasic is not enabled Feb 23 15:49:14 volumio volumio[31657]: info: Loading plugin "peppyspectrum"... Feb 23 15:49:14 volumio volumio-remote-updater[26837]: [2026-02-23 15:49:14] [connect] Successful connection Feb 23 15:49:14 volumio volumio[31657]: info: Loading i18n strings for locale en Feb 23 15:49:14 volumio volumio[31657]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 23 15:49:14 volumio volumio[31657]: Updating browse sources language Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::initPlayerControls Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 15:49:14 volumio volumio[31657]: Express server listening on port 3000 Feb 23 15:49:14 volumio volumio[31657]: [Metrics] WebUI: 16s 179.37ms Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::resetVolumioState Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::getcurrentVolume Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:14 volumio sudo[31857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 15:49:14 volumio sudo[31857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:14 volumio sudo[31857]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:14 volumio sudo[31859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 15:49:14 volumio sudo[31859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:14 volumio sudo[31859]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:14 volumio volumio[31657]: info: Volumio Network Manager: Network status updated: 1 Feb 23 15:49:14 volumio volumio[31657]: info: VolumeController:: Volume=100 Mute =false Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::pushState Feb 23 15:49:14 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioPushState Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::updateTrackBlock Feb 23 15:49:14 volumio volumio[31657]: info: CorePlayQueue::getTrackBlock Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 15:49:14 volumio volumio-remote-updater[26837]: [2026-02-23 15:49:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771883354 101 Feb 23 15:49:14 volumio volumio[31657]: 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 Feb 23 15:49:14 volumio volumio[31657]: info: Reloading queue from file Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::setRepeat null single undefined Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::pushState Feb 23 15:49:14 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioPushState Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::setRandom null Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::pushState Feb 23 15:49:14 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioPushState Feb 23 15:49:14 volumio volumio[31657]: info: Setting Device type: Raspberry PI Feb 23 15:49:14 volumio volumio[31657]: info: VolumeController:: Volume=100 Mute =false Feb 23 15:49:14 volumio volumio[31657]: info: CoreStateMachine::pushState Feb 23 15:49:14 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:14 volumio volumio[31657]: info: CoreCommandRouter::volumioPushState Feb 23 15:49:15 volumio volumio[31657]: info: Completed loading Core Plugins Feb 23 15:49:15 volumio volumio[31657]: info: Preparing to generate the ALSA configuration file Feb 23 15:49:15 volumio sudo[31872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 15:49:15 volumio sudo[31872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:15 volumio volumio[31657]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 23 15:49:15 volumio volumio[31657]: info: Discovery: Found device Volumio Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioGetState Feb 23 15:49:15 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:15 volumio volumio[31657]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 23 15:49:15 volumio volumio[31657]: info: Discovery: Found device Volumio Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioGetState Feb 23 15:49:15 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:15 volumio volumio[31657]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 23 15:49:15 volumio volumio[31657]: info: Reading ALSA contributions from plugins. Feb 23 15:49:15 volumio volumio[31657]: info: Asound.conf file unchanged, so no further update is needed Feb 23 15:49:15 volumio volumio[31657]: info: Output device has changed, restarting MPD Feb 23 15:49:15 volumio sudo[31872]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:15 volumio volumio[31657]: info: Output device has changed, restarting Shairport Sync Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:15 volumio sudo[31875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 15:49:15 volumio sudo[31875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:15 volumio sudo[31877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 15:49:15 volumio sudo[31877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:15 volumio sudo[31875]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:15 volumio volumio[31657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 15:49:15 volumio volumio[31657]: info: ___________ START PLUGINS ___________ Feb 23 15:49:15 volumio volumio[31657]: info: ControllerMpd::onStart: Initializing MPD Feb 23 15:49:15 volumio volumio[31657]: info: Creating MPD Configuration file Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355177] CoreMusicLibrary::Adding element Media Servers Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio sudo[31885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 15:49:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 15:49:15 volumio sudo[31885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:15 volumio sudo[31885]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:15 volumio sudo[31887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 15:49:15 volumio sudo[31887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355286] CoreMusicLibrary::Adding element Last_100 Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355300] CoreMusicLibrary::Adding element Webradio Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 15:49:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 15:49:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 15:49:15 volumio systemd[1]: mpd.service: Consumed 4.407s CPU time. Feb 23 15:49:15 volumio volumio[31657]: info: Initializing BBC Radios Feb 23 15:49:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 15:49:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 15:49:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355374] CoreMusicLibrary::Adding element Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355389] CoreMusicLibrary::Adding element SoundCloud Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: info: Creating Spotify config file Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:15 volumio sudo[31912]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 15:49:15 volumio sudo[31912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 15:49:15 volumio sudo[31912]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:15 volumio volumio[31657]: info: [squeezelite_mc] Starting proxy server... Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355762] CoreMusicLibrary::Adding element YouTube2 Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355780] CoreMusicLibrary::Adding element YouTube Music Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355792] CoreMusicLibrary::Adding element Podcast Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Podcast Feb 23 15:49:15 volumio volumio[31657]: info: Loading i18n strings for locale en Feb 23 15:49:15 volumio volumio[31657]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 23 15:49:15 volumio volumio[31657]: Updating browse sources language Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Podcast Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Podcast Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:15 volumio volumio[31657]: info: [1771883355840] CoreMusicLibrary::Adding element Volusonic Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Podcast Feb 23 15:49:15 volumio volumio[31657]: Cannot find translation for source Volusonic Feb 23 15:49:15 volumio volumio[31657]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 23 15:49:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 23 15:49:15 volumio volumio[31657]: info: Volumio Calling Home Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Proxy server started on port 34383 Feb 23 15:49:16 volumio volumio[31657]: info: Preparing to generate the ALSA configuration file Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 15:49:16 volumio volumio[31657]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 23 15:49:16 volumio volumio[31657]: info: Reading ALSA contributions from plugins. Feb 23 15:49:16 volumio volumio[31657]: info: MPD Permissions set Feb 23 15:49:16 volumio volumio[31657]: info: MPD Permissions set Feb 23 15:49:16 volumio volumio[31657]: info: Upmpdcli Daemon Started Feb 23 15:49:16 volumio volumio[31657]: info: Spotify config file written Feb 23 15:49:16 volumio sudo[31922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 15:49:16 volumio sudo[31922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio volumio[31657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 23 15:49:16 volumio volumio[31657]: info: VolumeController:: Volume=100 Mute =false Feb 23 15:49:16 volumio volumio[31657]: info: CoreStateMachine::pushState Feb 23 15:49:16 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::volumioPushState Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Server discovery started Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Player finder started Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 23 15:49:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: No need to fix Spotify hosts Feb 23 15:49:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:16 volumio go-librespot[31926]: go-librespot daemon starting... Feb 23 15:49:16 volumio sudo[31922]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=debug msg="app state loaded" Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:16 volumio volumio[31657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 23 15:49:16 volumio volumio[31657]: info: Volumio called home Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 23 15:49:16 volumio volumio[31657]: 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 Feb 23 15:49:16 volumio sudo[31946]: 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 Feb 23 15:49:16 volumio volumio[31657]: info: Starting Shairport Sync Feb 23 15:49:16 volumio sudo[31946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio sudo[31946]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:16 volumio volumio[31657]: info: Starting Shairport Sync Feb 23 15:49:16 volumio volumio[31657]: info: Starting Shairport Sync Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-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]" Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-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]" Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-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]" Feb 23 15:49:16 volumio sudo[31949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 15:49:16 volumio sudo[31949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=info msg="zeroconf server listening on port 36201" Feb 23 15:49:16 volumio volumio[31657]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 15:49:16 volumio volumio[31657]: SPOTIFY: BQBlAt6Z7WxLgLA2ODPZGDToHpCRnpuCGFjxqbWWjzrYibfIIGjvHyTXOuHmlQqBoCzjbuOeZuPT6RsT6OdyQ9nzQ6zSl7oIvopIx2r5E5e6ftLTK6htOn_TRBbayyV7y5lvH8hrPoGT_ewtFQEz5p24gFo--BTbg1KzGYuwLqfPekAFZZ4hAjpCVoUusmJKMYBFjtTlZow5QNm-FUwEEoZJeBQoJhtEsbC91Jg Feb 23 15:49:16 volumio volumio[31657]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 15:49:16 volumio sudo[31953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 15:49:16 volumio volumio[31657]: info: New Spotify access token = BQBlAt6Z7WxLgLA2ODPZGDToHpCRnpuCGFjxqbWWjzrYibfIIGjvHyTXOuHmlQqBoCzjbuOeZuPT6RsT6OdyQ9nzQ6zSl7oIvopIx2r5E5e6ftLTK6htOn_TRBbayyV7y5lvH8hrPoGT_ewtFQEz5p24gFo--BTbg1KzGYuwLqfPekAFZZ4hAjpCVoUusmJKMYBFjtTlZow5QNm-FUwEEoZJeBQoJhtEsbC91Jg Feb 23 15:49:16 volumio sudo[31951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 15:49:16 volumio sudo[31953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio volumio[31657]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 15:49:16 volumio sudo[31951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 15:49:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 15:49:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:16 volumio systemd[1]: shairport-sync.service: Consumed 1.948s CPU time. Feb 23 15:49:16 volumio volumio[31657]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 23 15:49:16 volumio volumio[31657]: info: Asound.conf file unchanged, so no further update is needed Feb 23 15:49:16 volumio volumio[31657]: info: Output device has changed, restarting MPD Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=debug msg="obtained new client token: AAAr7jdTCBZd/Wu28GFTO64Xhm86zORlgc+E+cFcTQB3xFMESDmjflIDhTF0jcNwAd+MTRg/5BC2SbPTAfAUJZb5TVIROASgE3gDXq8svurYLwNVtwaez06i3uCKarZ4oaXBs7oNIjY32ZsZELPAvGqUyowus1uuZ0Q8UwZ8gFRKLBnEqIQZKJKKoV+SOqwf1qa3CXLFq1eepoXHWUtsTutYpflq3SBv26bUdWjcwcDOTPtODrWaS8OfQA==" Feb 23 15:49:16 volumio volumio[31657]: info: Output device has changed, restarting Shairport Sync Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:16 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:16 volumio sudo[31964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 15:49:16 volumio sudo[31962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 15:49:16 volumio sudo[31964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio volumio[31657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 15:49:16 volumio sudo[31962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:16 volumio go-librespot[31937]: time="2026-02-23T15:49:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:16 volumio sudo[31949]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio sudo[31959]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 23 15:49:17 volumio sudo[31959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:17 volumio sudo[31962]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio go-librespot[31937]: time="2026-02-23T15:49:17-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:17 volumio go-librespot[31937]: time="2026-02-23T15:49:17-06:00" level=debug msg="completed challenge" Feb 23 15:49:17 volumio sudo[31959]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 15:49:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 15:49:17 volumio sudo[31951]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:17 volumio go-librespot[31937]: time="2026-02-23T15:49: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 " Feb 23 15:49:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:17 volumio sudo[31953]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 15:49:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 15:49:17 volumio systemd[1]: mpd.service: Consumed 1.601s CPU time. Feb 23 15:49:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 15:49:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 15:49:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 15:49:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 15:49:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 15:49:17 volumio volumio[31657]: info: MPD Permissions set Feb 23 15:49:17 volumio volumio[31657]: info: Shairport-Sync Started Feb 23 15:49:17 volumio volumio[31657]: Error adding Membership: Error: addMembership EINVAL Feb 23 15:49:17 volumio volumio[31657]: info: Shairport-Sync Started Feb 23 15:49:17 volumio volumio[31657]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 23 15:49:17 volumio sudo[31994]: 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 Feb 23 15:49:17 volumio sudo[31994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:17 volumio sudo[31994]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio volumio[31657]: info: Shairport-Sync Started Feb 23 15:49:17 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio sudo[31998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 15:49:17 volumio sudo[31998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:17 volumio systemd[1]: Reloading. Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::volumioGetState Feb 23 15:49:17 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:17 volumio sudo[31987]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 15:49:17 volumio sudo[31987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 15:49:17 volumio sudo[31987]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:17 volumio volumio[31657]: info: Starting Shairport Sync Feb 23 15:49:17 volumio sudo[32007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 15:49:17 volumio sudo[32007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:17 volumio volumio[31657]: 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"} Feb 23 15:49:17 volumio volumio[31657]: info: Spotify Successfully logged in Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 15:49:17 volumio volumio[31657]: info: [1771883357698] CoreMusicLibrary::Adding element Spotify Feb 23 15:49:17 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source Bandcamp Discover Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source SoundCloud Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source YouTube2 Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source YouTube Music Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source Podcast Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source Volusonic Feb 23 15:49:17 volumio volumio[31657]: Cannot find translation for source Spotify Feb 23 15:49:18 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:18 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:18 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:18 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 15:49:19 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. Feb 23 15:49:19 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. Feb 23 15:49:19 volumio sudo[31998]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:19 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:19 volumio sudo[32028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:19 volumio sudo[32028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 15:49:19 volumio sudo[32028]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 15:49:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:19 volumio systemd[1]: shairport-sync.service: Consumed 1.512s CPU time. Feb 23 15:49:19 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 23 15:49:19 volumio sudo[32032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 23 15:49:19 volumio sudo[32032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 15:49:19 volumio sudo[32007]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:19 volumio volumio[31657]: info: Shairport-Sync Started Feb 23 15:49:19 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 23 15:49:19 volumio volumio[31657]: info: go-librespot daemon successfully initialized Feb 23 15:49:19 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 23 15:49:19 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 23 15:49:19 volumio sudo[32032]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:20 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:20 volumio sudo[32049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:20 volumio sudo[32049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 23 15:49:20 volumio sudo[32049]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:20 volumio volumio[31657]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 23 15:49:20 volumio sudo[32054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 23 15:49:20 volumio sudo[32054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:20 volumio sudo[32054]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:20 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 23 15:49:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:20 volumio go-librespot[32051]: go-librespot daemon starting... Feb 23 15:49:20 volumio sudo[32057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 23 15:49:20 volumio sudo[32057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=debug msg="app state loaded" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:20 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 23 15:49:20 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 23 15:49:20 volumio sudo[32057]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-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]" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-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]" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=info msg="zeroconf server listening on port 43085" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=debug msg="obtained new client token: AABAM+FRqW58s7R5maWVgHWbyGxSJCOh/VFokQSrULnawttQrK6tbnTGIzBgMwvp0ep4twHD7d29QZ2Mh1enPOHcdCCeF/RFU01OYj4GxMZyegXA6PUN62Ykj5zkTKigl0d8ZPIeVzAS/BntDensc2aUDTVf/0HFi2bTvFldYVKJALsS52Uc8T3ZM1Icz8JqnrQp+Igq0APm21DhFmomRiIRgIJfq2+eOrGU7i7bI46ViPBL/9GXm8Iyrw==" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-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" Feb 23 15:49:20 volumio go-librespot[32058]: time="2026-02-23T15:49:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 15:49:21 volumio go-librespot[32058]: time="2026-02-23T15:49:21-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:21 volumio go-librespot[32058]: time="2026-02-23T15:49:21-06:00" level=debug msg="completed challenge" Feb 23 15:49:21 volumio go-librespot[32058]: time="2026-02-23T15:49: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 " Feb 23 15:49:21 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:21 volumio sudo[32071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:21 volumio sudo[32071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:21 volumio sudo[32071]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:21 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:21 volumio sudo[32088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:21 volumio sudo[32088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:21 volumio sudo[32088]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:22 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:22 volumio sudo[32091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:22 volumio sudo[32091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:22 volumio mpd[32026]: 2026-02-23T15:49:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 15:49:22 volumio sudo[32091]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 15:49:22 volumio sudo[31877]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:22 volumio sudo[31887]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:22 volumio sudo[31964]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:22 volumio volumio[31657]: error: MPD error: The expression evaluated to a falsy value: Feb 23 15:49:22 volumio volumio[31657]: assert.ok(self.idling) Feb 23 15:49:22 volumio volumio[31657]: error: The expression evaluated to a falsy value: Feb 23 15:49:22 volumio volumio[31657]: assert.ok(self.idling) Feb 23 15:49:22 volumio volumio[31657]: error: MPD error: The expression evaluated to a falsy value: Feb 23 15:49:22 volumio volumio[31657]: assert.ok(self.idling) Feb 23 15:49:22 volumio volumio[31657]: error: The expression evaluated to a falsy value: Feb 23 15:49:22 volumio volumio[31657]: assert.ok(self.idling) Feb 23 15:49:22 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:22 volumio volumio[31657]: info: MPD running with PID32026 Feb 23 15:49:22 volumio volumio[31657]: ,establishing connection Feb 23 15:49:22 volumio volumio[31657]: error: updateQueue error: null Feb 23 15:49:22 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:22 volumio volumio[31657]: error: updateQueue error: null Feb 23 15:49:22 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:22 volumio sudo[32098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:22 volumio sudo[32098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:22 volumio sudo[32098]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:23 volumio volumio[31657]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 15:49:23 volumio sudo[32101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 15:49:23 volumio sudo[32101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:23 volumio sudo[32101]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:23 volumio volumio[31657]: info: Completed starting Core Plugins Feb 23 15:49:23 volumio volumio[31657]: info: ------------------------------------------- Feb 23 15:49:23 volumio volumio[31657]: info: ----- MyVolumio plugins startup ---- Feb 23 15:49:23 volumio volumio[31657]: info: ------------------------------------------- Feb 23 15:49:23 volumio volumio[31657]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 15:49:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 23 15:49:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:24 volumio go-librespot[32103]: go-librespot daemon starting... Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="app state loaded" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-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]" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-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]" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=info msg="zeroconf server listening on port 33333" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="obtained new client token: AAA3v9S5nHX+2yJEDW05smR4NAwVftZK5MHv5AyUUs7n1VscDMNHt6vQaFXjZiynKdTq6/4/RmJ6/xH5VkDikS84XvuMjRaQa/KiyyfPc3EhlVk0YqYDc9irK6lgCWU0yYHPuBf+vnW2hNJy9EL9392tSqhnPk/y6bqrt6tZnwtq0SRftDhJDXcQueYs0E+1wBdMb9Zvgp9pQPNvPik+ZIsIDv3eowDqCBCxG2THYEdArxGP0iL10DoxXw==" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49:24-06:00" level=debug msg="completed challenge" Feb 23 15:49:24 volumio go-librespot[32104]: time="2026-02-23T15:49: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 " Feb 23 15:49:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:25 volumio sudo[32112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 15:49:25 volumio sudo[32112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:25 volumio sudo[32114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 15:49:25 volumio sudo[32114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:25 volumio sudo[32118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 15:49:25 volumio sudo[32118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:25 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 23 15:49:25 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 23 15:49:25 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 23 15:49:25 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:25 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:25 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 23 15:49:25 volumio sudo[32112]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:25 volumio mpd_monitor.sh[32120]: MPD Monitor Service: Starting MPD Monitor Service Feb 23 15:49:25 volumio sudo[32114]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:25 volumio volumio[31657]: info: Successfully started MPD Monitor Feb 23 15:49:25 volumio sudo[32118]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:25 volumio volumio[31657]: info: Successfully started MPD Monitor Feb 23 15:49:25 volumio volumio[31657]: info: Successfully started MPD Monitor Feb 23 15:49:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 23 15:49:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:27 volumio go-librespot[32124]: go-librespot daemon starting... Feb 23 15:49:27 volumio go-librespot[32125]: time="2026-02-23T15:49:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:27 volumio go-librespot[32125]: time="2026-02-23T15:49:27-06:00" level=debug msg="app state loaded" Feb 23 15:49:27 volumio go-librespot[32125]: time="2026-02-23T15:49:27-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:27 volumio go-librespot[32125]: time="2026-02-23T15:49:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49: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]" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49: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]" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49: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]" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49:28-06:00" level=info msg="zeroconf server listening on port 46311" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49:28-06:00" level=debug msg="obtained new client token: AABAJbkdk8K230qDE/5tHU2kBdcOmXjFhNRoyA3UkzejPNy6AXhVo4ZDllejraCIHxiZWurU+8b6aPA2TfgNRKHPT4ypSE/Rbj9hGRPzrV1+hVxXBi/nn4aW2J5YlYxdKC2jPJNV5EySnMHH1jMjFa3kOEkD35AwV/+YCiJ3LZbP8Z+9vhLmZVrSxYkIpXIDMTa9mf1nXCpEoq50OOPbjj9km/klMFTtbRBhF+rfb/Lo2zStjVJ/eAiTaQ==" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49:28-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49:28-06:00" level=debug msg="completed challenge" Feb 23 15:49:28 volumio go-librespot[32125]: time="2026-02-23T15:49: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 " Feb 23 15:49:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:29 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:30 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 23 15:49:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:31 volumio go-librespot[32147]: go-librespot daemon starting... Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-06:00" level=debug msg="app state loaded" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 15:49:31 volumio volumio[31657]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-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]" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-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]" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-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]" Feb 23 15:49:31 volumio go-librespot[32148]: time="2026-02-23T15:49:31-06:00" level=info msg="zeroconf server listening on port 34759" Feb 23 15:49:32 volumio go-librespot[32148]: time="2026-02-23T15:49:32-06:00" level=debug msg="obtained new client token: AACG34pwqCgMgm638XEGo67ci6JP5Q03IQtFxBdo6xlct4cf807y2Pho+vVip+E4qe0L0/Tc4AijqLe+/mYSFvDIAPHHG+jeszY2fUb/pPGWtKSMHLBNnsQoPj0o1+ng6jWzzD4A+Ej4WpvJgx9qNfipaXlWgphyIYPZwnEsRnZFyX++ntjVgnimeF7Is5ZkiSzG3fePJ1+vDOSHsTV784QK+coMcFJBhkN25nsnHg+omzVaLbNjmw8=" Feb 23 15:49:32 volumio go-librespot[32148]: time="2026-02-23T15:49:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:32 volumio go-librespot[32148]: time="2026-02-23T15:49:32-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:32 volumio go-librespot[32148]: time="2026-02-23T15:49:32-06:00" level=debug msg="completed challenge" Feb 23 15:49:32 volumio go-librespot[32148]: time="2026-02-23T15:49: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 " Feb 23 15:49:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:32 volumio volumio[31657]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 15:49:32 volumio volumio[31657]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 15:49:32 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:32 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:32 volumio volumio[31657]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 15:49:32 volumio volumio[31657]: info: MyVolumio login type: Token Feb 23 15:49:32 volumio volumio[31657]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 15:49:32 volumio volumio[31657]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 15:49:33 volumio volumio[31657]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 15:49:33 volumio volumio[31657]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 15:49:33 volumio volumio[31657]: info: Streaming services startup Feb 23 15:49:33 volumio volumio[31657]: info: Starting Streaming Daemon Feb 23 15:49:33 volumio sudo[32156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 15:49:33 volumio sudo[32156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 15:49:33 volumio volumio[31657]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 15:49:33 volumio sudo[32156]: pam_unix(sudo:session): session closed for user root Feb 23 15:49:33 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:33 volumio volumio[31657]: error: Cannot start Volumio Streaming Daemon Feb 23 15:49:33 volumio volumio[31657]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 15:49:33 volumio volumio[31657]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 15:49:33 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:33 volumio volumio[31657]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required. Feb 23 15:49:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 23 15:49:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:35 volumio go-librespot[32163]: go-librespot daemon starting... Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="app state loaded" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-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]" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-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]" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=info msg="zeroconf server listening on port 43899" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="obtained new client token: AADLDgY6nHKy90hKxDauEqk2R37cvO6JLhBX3jXw+BcK/ERvvwWll++828+VweHadJ3cSsf5QCSu7ltDcrfF4qqcTGB+/3ZokkANMOos4eshwY1YlEoH8bbP3KZQCFv1eWs7ym/R1sipsB3x7NQXkhnea4xImSOra6RnKz1iUCrpozpaoqPOnu507Y6h8H+2nRaZOq56ot9y/uH9fq5I8vXrLKkdLZsdkV79EMQRaCF83kfdhSsGeU2osg==" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-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" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:35 volumio go-librespot[32164]: time="2026-02-23T15:49:35-06:00" level=debug msg="completed challenge" Feb 23 15:49:36 volumio go-librespot[32164]: time="2026-02-23T15:49: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 " Feb 23 15:49:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:36 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:36 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 23 15:49:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:39 volumio go-librespot[32171]: go-librespot daemon starting... Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="app state loaded" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-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]" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-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]" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=info msg="zeroconf server listening on port 36059" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="obtained new client token: AACwE48MnMSBPGxgq7hfBu2QWahtdGRWHcAEzm67mxhCsgB+DQGIudmbv2ghZcQtYlIKHuWhgQNuB3I7XtNW+MigrqXuWRT63fhjRHfd0qvdM1Hm4IpWyma+QodkGSC6QNXG7y1VL0w/CMYKg+6hsfRFf03CJGTLWcQRejDJX/INWc+bNPTbN4VL1w95p0Yh6xPD4/c5Ttnnk0ykw33x943H0H6RzQZOCfxQcd9noBm1DkB50n1K8gttnQ==" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49:39-06:00" level=debug msg="completed challenge" Feb 23 15:49:39 volumio go-librespot[32172]: time="2026-02-23T15:49: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 " Feb 23 15:49:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:39 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:39 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:42 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:42 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 23 15:49:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:42 volumio go-librespot[32193]: go-librespot daemon starting... Feb 23 15:49:42 volumio go-librespot[32194]: time="2026-02-23T15:49:42-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:42 volumio go-librespot[32194]: time="2026-02-23T15:49:42-06:00" level=debug msg="app state loaded" Feb 23 15:49:42 volumio go-librespot[32194]: time="2026-02-23T15:49:42-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:42 volumio go-librespot[32194]: time="2026-02-23T15:49:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-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]" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-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]" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-06:00" level=info msg="zeroconf server listening on port 46275" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-06:00" level=debug msg="obtained new client token: AACjtJg3QOJVt0pGPkv6/orjYLYKSiRlpS7eb8qd6nA+eJN5U4kxT1wlzpYWolG7yW1fC5ym3+Cn3h6ZTbC+UwI1OQR05E4cZzpEDIyD3Jon7PJTR2VSUcwu2BvOXKOrYlqojge0KjVwLgg0TrKamJaPp60EMhjlzt1/aR3u5R/aQJUXVUPw4tD5ZAgXsL7TnE1tj1vvfrZNPCnA9brPaDv4C+S4gRbErRejIbOLTYpb77kl1MsSK97T5Q==" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49: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" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49:43-06:00" level=debug msg="completed challenge" Feb 23 15:49:43 volumio go-librespot[32194]: time="2026-02-23T15:49: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 " Feb 23 15:49:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:45 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:45 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:45 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 15:49:45 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:45 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 15:49:45 volumio volumio-remote-updater[26837]: Test mode disabled Feb 23 15:49:45 volumio volumio-remote-updater[26837]: Alpha mode disabled Feb 23 15:49:45 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled Feb 23 15:49:45 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 23 15:49:46 volumio volumio[31657]: 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} Feb 23 15:49:46 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 15:49:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 23 15:49:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:46 volumio go-librespot[32208]: go-librespot daemon starting... Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-06:00" level=debug msg="app state loaded" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-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]" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-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]" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-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]" Feb 23 15:49:46 volumio go-librespot[32209]: time="2026-02-23T15:49:46-06:00" level=info msg="zeroconf server listening on port 33307" Feb 23 15:49:47 volumio go-librespot[32209]: time="2026-02-23T15:49:47-06:00" level=debug msg="obtained new client token: AAAgnq6jhwcxGAB9/aqDugh+CAPZSNjvxDarIHcoyTyI9+HUv6DKetzkI7HNf13EQuITg9xcrXoNNj0rCA57DJPjy3yAQQnHHjz8lfX+jI4i6zR+8SVvJRucqZyygLJ5vGOxRM0FZJxZ6HJtYz6tcybdiuweoP0tXV+4qv+9CRouVRTOVR/rSetQLgAPRZSDF0gnhPTnU+POaavG2E3VkBnCIwboxBypXvX2QzribTdHioCJ7052jFU=" Feb 23 15:49:47 volumio go-librespot[32209]: time="2026-02-23T15:49:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:47 volumio go-librespot[32209]: time="2026-02-23T15:49:47-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:47 volumio go-librespot[32209]: time="2026-02-23T15:49:47-06:00" level=debug msg="completed challenge" Feb 23 15:49:47 volumio go-librespot[32209]: time="2026-02-23T15:49: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 " Feb 23 15:49:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:47 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 15:49:47 volumio volumio[31657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 23 15:49:47 volumio volumio[31657]: info: CoreCommandRouter::volumioGetState Feb 23 15:49:47 volumio volumio[31657]: info: CorePlayQueue::getTrack 0 Feb 23 15:49:48 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:48 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 23 15:49:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:50 volumio go-librespot[32217]: go-librespot daemon starting... Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="app state loaded" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-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]" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-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]" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=info msg="zeroconf server listening on port 40219" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="obtained new client token: AACS/BtW5r2Tt4kp8Mg7o5wCFOKnEHRGeZSBExlTpVzgvZ/VpNyjfRqpgjP1IXkIjm1Eh7YK2NgWauOkQiTg3kA2EY4qSNJoyAArX5fSm5Ydcf9E0zon+IsQTWwtuEky7I4LkpzQQcAp+QOj6MbCHcCbYGMaZMRv8kVZRbPtbq7QL9pabig05iZ9iOEb5Me3oFpQNt0ujYjQumFfSXcpWsfSd04hN5KBuMv8H4VZE9TmfZRh97YHM20R+Q==" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-06:00" level=debug msg="completed challenge" Feb 23 15:49:50 volumio go-librespot[32218]: time="2026-02-23T15:49:50-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 " Feb 23 15:49:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:51 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:51 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 15:49:52 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 15:49:53 volumio volumio[31657]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 15:49:53 volumio volumio[31657]: info: Completed starting MyVolumio Plugin Feb 23 15:49:53 volumio volumio[31657]: [Metrics] CommandRouter: 54s 745.19ms Feb 23 15:49:53 volumio volumio[31657]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 15:49:53 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 15:49:53 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 15:49:53 volumio volumio[31657]: info: CoreCommandRouter::Close All Modals sent Feb 23 15:49:53 volumio volumio[31657]: info: CoreCommandRouter::Close All Modals sent Feb 23 15:49:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 23 15:49:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:54 volumio go-librespot[32242]: go-librespot daemon starting... Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="app state loaded" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-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]" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-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]" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=info msg="zeroconf server listening on port 38199" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="obtained new client token: AAC3gYw7aduDpUcAT/thCuv0kHDFgCgOLgOENQ3rb4s0Fay4Bmjg+gsj2EAwdd6YYhLn4oQBOYx5dUdl0GCtrPLgcn0AvFMzynDno210bTA+TdifPC73gU/+WXwh8jIOX7422A74551aIUqvx67ux7USc51VhEceOAibGfXCL0qZZuIidIrUU+e/MVrE+3EuM2ue+Zxbec1USocEz/MyyiWGw0894H0NJV7BpkA+Oi0qoh4hH617Y4FtSQ==" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49:54-06:00" level=debug msg="completed challenge" Feb 23 15:49:54 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 15:49:54 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 15:49:54 volumio go-librespot[32243]: time="2026-02-23T15:49: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 " Feb 23 15:49:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:49:54 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 15:49:54 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:54 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:57 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:49:57 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:49:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 23 15:49:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:49:57 volumio go-librespot[32253]: go-librespot daemon starting... Feb 23 15:49:57 volumio go-librespot[32254]: time="2026-02-23T15:49:57-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:49:57 volumio go-librespot[32254]: time="2026-02-23T15:49:57-06:00" level=debug msg="app state loaded" Feb 23 15:49:57 volumio go-librespot[32254]: time="2026-02-23T15:49:57-06:00" level=debug msg="stored credentials not found" Feb 23 15:49:57 volumio go-librespot[32254]: time="2026-02-23T15:49:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-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]" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-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]" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-06:00" level=info msg="zeroconf server listening on port 40083" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-06:00" level=debug msg="obtained new client token: AABfJaouwV8z2MQc7lmfMQwT7f3mPprSty0UDNntRHelUCR+SaXrXW/i2kEuor6eY33lA+d2QVDHHMh5tv+gF/YXFy95W8pgBBXDVYsXxyVaI6pLgeWqXuRtoT76AlmzBnpOaPG1ME25bQ0Q4dSQvwfU+RWNbPzi5xK9QbPhwjIGuBjglL9lTkGBspU229wIgbUJbJKJJlTopcgO8Gb0RUmb/7FipuPr612SAHcdbI9om/v3Rw/2bc3xmA==" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-06:00" level=debug msg="completed keyexchange" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49:58-06:00" level=debug msg="completed challenge" Feb 23 15:49:58 volumio go-librespot[32254]: time="2026-02-23T15:49: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 " Feb 23 15:49:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:49:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:00 volumio volumio[31657]: info: BOOT COMPLETED Feb 23 15:50:00 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:00 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 23 15:50:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:01 volumio go-librespot[32275]: go-librespot daemon starting... Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-06:00" level=debug msg="app state loaded" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-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]" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-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]" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-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]" Feb 23 15:50:01 volumio go-librespot[32276]: time="2026-02-23T15:50:01-06:00" level=info msg="zeroconf server listening on port 44735" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-06:00" level=debug msg="obtained new client token: AADJ2iBj/mfEY5urDKwiZaJPSRmYoUcctiNq++IA4vaUTusJe1spEg2GQXEwGGqbVL0S2tD8xYTOUKV1BTN6VtHyjaByn2Uh+j3WsT96/8Lp6MG6R9PdXDuKlrBVqmWSqINk+4tZzJ5G5V3L8hSkV95wd9MGcQzDwNF8/JAKGGfcxsaePiIHOEmqYq1c9Qh2rPgG7hgsGV9M2ldOsSYqzFv1xXUudMs7N6OxhdhXVMXsvTdNDYKc8IM=" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-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" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-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" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50:02-06:00" level=debug msg="completed challenge" Feb 23 15:50:02 volumio go-librespot[32276]: time="2026-02-23T15:50: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 " Feb 23 15:50:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:03 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:03 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 23 15:50:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:05 volumio go-librespot[32283]: go-librespot daemon starting... Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="app state loaded" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50: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-gew1.spotify.com:80]" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-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]" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-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]" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=info msg="zeroconf server listening on port 34853" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="obtained new client token: AABcRyqr2PC3yDjG5NMOI+jMbkrLjRa8v7e27cOab+x4iwKcul8cjdsD+6ONXgv1XkUGeiB6ATPxry5YpYRA8pMocSg1adUsE6ovv6Ufry7vnTijmAhrkxj9Fu7KjhdUZUpmDtLtJBIk2FhrGnUXGWgh8r2nUGfEIx1EC8MVUYizKNWr+csUaK+FDR72MNzPJsdS/HDJr24a+NRQo4UGL5mmQ9yTOk/njxPHuG4rq9I5IqUkHMFogry43w==" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-06:00" level=debug msg="completed challenge" Feb 23 15:50:05 volumio go-librespot[32284]: time="2026-02-23T15:50:05-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 " Feb 23 15:50:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:06 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:06 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 23 15:50:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:09 volumio go-librespot[32294]: go-librespot daemon starting... Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="app state loaded" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-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]" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-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]" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=info msg="zeroconf server listening on port 43795" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="obtained new client token: AACqNTOgsOtdjcJuWAnqXoQhxgrkrLUO/Rgn78oN2N++WZMorGvHbLlY7OGIHCTSYDmK3/sqQQRf9j3Qtbe1RTyx1AnFnrhqXP+MP5ra5pfqn0C+diMfdbg6/iOGupk+oaN8uwtuZpOzfDZQ71nbUywFnUgH6OU9MNbJ24tyHjuJEwha6sHSyc6mmXCGdW0Au0DBUyDz7bm9hu0Tz9UhozOL3iHXTjRsHxc5N6paKtR7pwg80dzqd/gfGQ==" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50:09-06:00" level=debug msg="completed challenge" Feb 23 15:50:09 volumio go-librespot[32295]: time="2026-02-23T15:50: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 " Feb 23 15:50:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:09 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:09 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:12 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:12 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 23 15:50:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:12 volumio go-librespot[32317]: go-librespot daemon starting... Feb 23 15:50:12 volumio go-librespot[32318]: time="2026-02-23T15:50:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:12 volumio go-librespot[32318]: time="2026-02-23T15:50:12-06:00" level=debug msg="app state loaded" Feb 23 15:50:12 volumio go-librespot[32318]: time="2026-02-23T15:50:12-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:12 volumio go-librespot[32318]: time="2026-02-23T15:50:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50: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]" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50: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]" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50: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]" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50:13-06:00" level=info msg="zeroconf server listening on port 41489" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50:13-06:00" level=debug msg="obtained new client token: AADW95+tbNwETI4vnuI6KYMw9Xpt+eaINm3uD8LJn5Xzj/niWMOm+/Qzo4qoK67Fh9RB+PxPrlzM8Jp6gDoM6Mx/YQnLm/QQ5LgYtUb4FuyoiPJFZAEImOtVAKtrOcZp3tA6SBCc4T6XdDH2OW7y+wDW1v0T7ala2nCn85pLQHZrp1Uqw18AIG8dkkmxVrL/5/u7tJsoC/pa5bp7RUGYNAur3n2b672IoOQf6mu7Q2SlO8464DyOwjw=" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50:13-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50:13-06:00" level=debug msg="completed challenge" Feb 23 15:50:13 volumio go-librespot[32318]: time="2026-02-23T15:50: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 " Feb 23 15:50:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:15 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:15 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:15 volumio volumio[31657]: info: UPDATER: Scheduling automatic update Feb 23 15:50:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Feb 23 15:50:15 volumio volumio[31657]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Feb 23 15:50:15 volumio volumio[31657]: info: UPDATER: Auto update will take place at: Tue Feb 24 2026 04:52:42 GMT-0600 (Central Standard Time) Feb 23 15:50:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 23 15:50:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:16 volumio go-librespot[32325]: go-librespot daemon starting... Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-06:00" level=debug msg="app state loaded" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-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]" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-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]" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-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]" Feb 23 15:50:16 volumio go-librespot[32326]: time="2026-02-23T15:50:16-06:00" level=info msg="zeroconf server listening on port 44189" Feb 23 15:50:17 volumio go-librespot[32326]: time="2026-02-23T15:50:17-06:00" level=debug msg="obtained new client token: AACOvG2tpFHTWgIoYlfru8fyTXDYc4pmS5aUx8ROjcFBPZyPS+sEmWUEOrbTR/jo2U9DJDIZ+Ny6IcGZJ71j6U9TjMIGxoGtOp38dFMV8dVytMAs478oi1eBDG53AkpY8uBQdfOlPKgNcewpIQWhWnhD2L+jSvpxqH+akJOefMryf/bflAE1q72gxPO0cO8VVbubRxpV+yRqKAfiyJAxR/Bn+wf07TWL59FKdSuaBR7tZQQk16OMnVg=" Feb 23 15:50:17 volumio go-librespot[32326]: time="2026-02-23T15:50:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:17 volumio go-librespot[32326]: time="2026-02-23T15:50:17-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:17 volumio go-librespot[32326]: time="2026-02-23T15:50:17-06:00" level=debug msg="completed challenge" Feb 23 15:50:17 volumio go-librespot[32326]: time="2026-02-23T15:50: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 " Feb 23 15:50:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:18 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:18 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 23 15:50:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:20 volumio go-librespot[32333]: go-librespot daemon starting... Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="app state loaded" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50: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]" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50: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]" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50: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]" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=info msg="zeroconf server listening on port 39191" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="obtained new client token: AACrxEf/xctVgU7AB3knMChSpqPulVkCh3lxDE/9B+kzHfL7qXD9WwJSruRdbA3/12Np1ncFgd/HpN3xbAiK4SH283y/l0Yi08CQ4R5Xf3t2dQxDslcdk6m5OctmQmM92YRIhnVxH45e3+IuYK11Q20DI9o2fCv3ihgkKSe1xpQdVXS8wY0ZsGucWc9jzRuC66L4JsAzjupGSjEH+8+2w/y3Ek6ZDn/VRQjQnLAzJ/m/9mzN2sySZEzdhg==" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-06:00" level=debug msg="completed challenge" Feb 23 15:50:20 volumio go-librespot[32334]: time="2026-02-23T15:50:20-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 " Feb 23 15:50:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:21 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:21 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 23 15:50:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:24 volumio go-librespot[32355]: go-librespot daemon starting... Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="app state loaded" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-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]" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-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]" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=info msg="zeroconf server listening on port 37533" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="obtained new client token: AADOhrX3uxrNhm60mxryqZaX0UNMTJUEghPbvpYeno7zqM4XDfFr3kUcPs3FnRH5u6b0hbFvQhI7TdImFGUN4vO7myTzgi6XMaH9FQ3buEbya4koPP466Y8kJ28l4CXS0X55zVK6gd2TQWeKzlLvQeR2BkwNw5hKJZ2naT9OF3yAGbmWKjcI6elOmH1SVJHmwhTaYNxDMnFD5qi0NGY8NbuwSnfbdsK3uDt8bFRUq+k2LW4GA7Lo3HvbHg==" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50:24-06:00" level=debug msg="completed challenge" Feb 23 15:50:24 volumio go-librespot[32356]: time="2026-02-23T15:50: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 " Feb 23 15:50:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:24 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:24 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:27 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:27 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 23 15:50:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:27 volumio go-librespot[32366]: go-librespot daemon starting... Feb 23 15:50:27 volumio go-librespot[32367]: time="2026-02-23T15:50:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:27 volumio go-librespot[32367]: time="2026-02-23T15:50:27-06:00" level=debug msg="app state loaded" Feb 23 15:50:27 volumio go-librespot[32367]: time="2026-02-23T15:50:27-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:27 volumio go-librespot[32367]: time="2026-02-23T15:50:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-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]" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-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]" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-06:00" level=info msg="zeroconf server listening on port 45363" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-06:00" level=debug msg="obtained new client token: AAC/SuhJTdc7VrL7pPRiw495p6/tSWXJrZbKzJV2WgNZWgk/NOFe88qVFp185MTm8LPIUv+Cz2K+Xycb0SqYkWA+irJisoZiNHL4BCqH8tAhi8ptogPKmaqmabaNNDPPowcbI2box+yFjFoqpQW+YwL8wEBbOD/pCHpH4oxXG+v8ahGB+F3BbpXKMCx68yrg4flytf63vPe0KQ36Bm+PKsSXAEGFhvV3sNpEYiTNMTkeaQBmAQ0tpnw=" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50:28-06:00" level=debug msg="completed challenge" Feb 23 15:50:28 volumio go-librespot[32367]: time="2026-02-23T15:50: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 " Feb 23 15:50:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:30 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:30 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 23 15:50:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:31 volumio go-librespot[32389]: go-librespot daemon starting... Feb 23 15:50:31 volumio go-librespot[32390]: time="2026-02-23T15:50:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:31 volumio go-librespot[32390]: time="2026-02-23T15:50:31-06:00" level=debug msg="app state loaded" Feb 23 15:50:31 volumio go-librespot[32390]: time="2026-02-23T15:50:31-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:31 volumio go-librespot[32390]: time="2026-02-23T15:50:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50: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]" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50: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]" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50: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]" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-06:00" level=info msg="zeroconf server listening on port 46879" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-06:00" level=debug msg="obtained new client token: AACOEXnBYlzFbN9yFN/ia/uSaoxSngyytI1BReavElnXz1NzJmN+JJKeuqCzHeFViM5iV/a1e/vP3hiS+tRYLDzxJTn11tF8mhwsXcbbHi4/I9LEtiqmYsTkCo/0NG5tblOsJBR/+kUvTkQkjutdwsUyWeSEYUYPHBxebMXzCnqzliqFva9EDmKwB1Bpzcw9BI4ezkhjtVdhtBf5e4ENgs7sAIKCh6ugkAP5BCUyScjOc1YBDTkbu/c=" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-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" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50:32-06:00" level=debug msg="completed challenge" Feb 23 15:50:32 volumio go-librespot[32390]: time="2026-02-23T15:50: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 " Feb 23 15:50:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:33 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:33 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 23 15:50:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:35 volumio go-librespot[32398]: go-librespot daemon starting... Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-06:00" level=debug msg="app state loaded" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-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]" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-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]" Feb 23 15:50:35 volumio go-librespot[32399]: time="2026-02-23T15:50:35-06:00" level=info msg="zeroconf server listening on port 39641" Feb 23 15:50:36 volumio go-librespot[32399]: time="2026-02-23T15:50:36-06:00" level=debug msg="obtained new client token: AABioCPK1BhqhbEu8z91FpMYmVuWwtqy/CN36eKEnKCv9WVJvLg6WRqCjfmTmW3dz52+6wIeaxw7gfRcp6ICprPaxZSAxDmdhXlv79bH9RAthN0qeLhHHfOScb/ji6aLG0eeW8OzTZiCvnUdquTezFOm3l6a7l+9i5TR7sVrJ5Dwhx6vE6XPLdI/WDvSJl83SZKUINgUR8iSVzllcd9hvdlPXebay4YA0rSkhrvhypHuDOgH3MNwvT0=" Feb 23 15:50:36 volumio go-librespot[32399]: time="2026-02-23T15:50:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 15:50:36 volumio go-librespot[32399]: time="2026-02-23T15:50:36-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:36 volumio go-librespot[32399]: time="2026-02-23T15:50:36-06:00" level=debug msg="completed challenge" Feb 23 15:50:36 volumio go-librespot[32399]: time="2026-02-23T15:50: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 " Feb 23 15:50:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:36 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:36 volumio volumio[31657]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 23 15:50:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:39 volumio go-librespot[32406]: go-librespot daemon starting... Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="app state loaded" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50: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]" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50: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]" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50: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]" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=info msg="zeroconf server listening on port 36077" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="obtained new client token: AABhfOqfCt7G0EDxv3hiTsCPC1Eb21Z5JGldWNlre6OMCst92nTUxbA9FV1FCs5qqB2aS7Ltz3n3NAazLlqHvAvR9cpgp5hmRnltruT42ijfTwad84T7ignrXhK6PyiNQ37RDh8mq/7CrNZBkN7fHIhOCOYA4Nlb4RmzEY9DaTFCm6lKdUrMseP4J+k6JX+aqA8Q5cTqkCcUwW1pP52zODSokdynyK/dwFj0y19uW+Ej1VrYNwW9P+Zo6w==" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50: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" Feb 23 15:50:39 volumio volumio[31657]: info: Initializing connection to go-librespot Websocket Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="new websocket client" Feb 23 15:50:39 volumio volumio[31657]: info: Connection to go-librespot Websocket established Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="completed keyexchange" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50:39-06:00" level=debug msg="completed challenge" Feb 23 15:50:39 volumio go-librespot[32407]: time="2026-02-23T15:50: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 " Feb 23 15:50:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 15:50:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 15:50:39 volumio volumio[31657]: info: Connection to go-librespot Websocket closed Feb 23 15:50:42 volumio volumio[31657]: info: Getting Spotify volume Feb 23 15:50:42 volumio volumio[31657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 15:50:42 volumio volumio[31657]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 15:50:42 volumio volumio[31657]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 23 15:50:42 volumio volumio[31657]: errno: -111, Feb 23 15:50:42 volumio volumio[31657]: code: 'ECONNREFUSED', Feb 23 15:50:42 volumio volumio[31657]: syscall: 'connect', Feb 23 15:50:42 volumio volumio[31657]: address: '127.0.0.1', Feb 23 15:50:42 volumio volumio[31657]: port: 9879, Feb 23 15:50:42 volumio volumio[31657]: response: undefined Feb 23 15:50:42 volumio volumio[31657]: } Feb 23 15:50:42 volumio volumio[31657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 15:50:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 23 15:50:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 15:50:43 volumio go-librespot[32440]: go-librespot daemon starting... Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=debug msg="app state loaded" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=debug msg="stored credentials not found" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-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]" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-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]" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=info msg="zeroconf server listening on port 43819" Feb 23 15:50:43 volumio go-librespot[32441]: time="2026-02-23T15:50:43-06:00" level=debug msg="obtained new client token: AAAGqqSd8LIfayw8WmYpsuHfWA5H36TrVY4+CeALQo+jJJLVirKf46KoCJPN/OUX3xFXnOcCrkmq/njKlfj2hTqxWJ3cx42SnvOMrnYYNL1xcks4ukSASGwaZSM967eAmdAHbegJTInNHExeFj1aNyvSfjvj8R6zKupgMNdUzTI950Dmk24dPFLIK8TGJhvf+H7c+Y7ZY3iE25AD2dsdJU7yawxVFVeca5DCYzUNaa8S5UE2qAPpRT21Pg==" Feb 23 15:50:43 volumio sudo[32450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 15:49' Feb 23 15:50:43 volumio sudo[32450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"