Feb 17 10:28:00 volumio volumio[9370]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:28:00 volumio volumio[9370]: info: CoreCommandRouter::volumioGetState Feb 17 10:28:00 volumio volumio[9370]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:00 volumio volumio[9370]: info: CoreStateMachine::pushState Feb 17 10:28:00 volumio volumio[9370]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:00 volumio volumio[9370]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:01 volumio volumio[9370]: info: go-librespot daemon successfully initialized Feb 17 10:28:01 volumio mpd[9596]: 2026-02-17T10:28:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 10:28:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 10:28:01 volumio sudo[9547]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:01 volumio sudo[9557]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:01 volumio volumio[9370]: error: MPD error: The expression evaluated to a falsy value: Feb 17 10:28:01 volumio volumio[9370]: assert.ok(self.idling) Feb 17 10:28:01 volumio volumio[9370]: error: The expression evaluated to a falsy value: Feb 17 10:28:01 volumio volumio[9370]: assert.ok(self.idling) Feb 17 10:28:01 volumio volumio[9370]: info: MPD running with PID9596 Feb 17 10:28:01 volumio volumio[9370]: ,establishing connection Feb 17 10:28:01 volumio volumio[9370]: error: updateQueue error: null Feb 17 10:28:01 volumio volumio[9370]: info: Completed starting Core Plugins Feb 17 10:28:01 volumio volumio[9370]: info: ------------------------------------------- Feb 17 10:28:01 volumio volumio[9370]: info: ----- MyVolumio plugins startup ---- Feb 17 10:28:01 volumio volumio[9370]: info: ------------------------------------------- Feb 17 10:28:01 volumio volumio[9370]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 10:28:01 volumio volumio[9370]: error: updateQueue error: null Feb 17 10:28:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 17 10:28:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:01 volumio go-librespot[9688]: go-librespot daemon starting... Feb 17 10:28:01 volumio go-librespot[9689]: time="2026-02-17T10:28:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:01 volumio go-librespot[9689]: time="2026-02-17T10:28:01+07:00" level=debug msg="app state loaded" Feb 17 10:28:01 volumio go-librespot[9689]: time="2026-02-17T10:28:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=info msg="zeroconf server listening on port 41325" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="obtained new client token: AADK4zcs340oOHtMrOig6KBNQhBMTyJBQeoaXsfUUuaYAEhd6+9SSrxgd0R3sNiour3bbtLtsXLXEIo445SzGU1yVP2b/DZ7nmamyBHm5P8hV3r+jkGVkDW64kq2evIlEQkQGochJnMTTahJ/wlT04lbXblgDmAXeNlQAG2rIhDseuRtUaweMpUa4YbaugxkKmsd7veC+mt1OgSFBlMOnreiJ2/fsaEn3H/x05Nm+pM1cpH34glBadirfA==" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=debug msg="completed challenge" Feb 17 10:28:02 volumio go-librespot[9689]: time="2026-02-17T10:28:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:04 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:04 volumio volumio[9370]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 17 10:28:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:06 volumio go-librespot[9697]: go-librespot daemon starting... Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="app state loaded" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:06 volumio volumio[9370]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=info msg="zeroconf server listening on port 36495" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="obtained new client token: AACQ1vsiLxLYomOZp0I5z4EafAzKZGAvYweMmBzbPdKALk0whotJ3fqU+ZzIRjK7LXE1hE3yIkIOxLNV5RjXeJFkMswZiGJNdZnXDzgRvmesVv55IrwZ9MUBbOwJu5Mwz+qAMUpwqywj7Fw74fvZUMF7LyCx31msGltaxzSMGvjFqRm/jHKK0Vw4eGIgEsx9Z8BTvJgRH938sxZEZ335rmuJ95l7M3MUBNmeSlSRGvcc551YlB9SFh4r/g==" Feb 17 10:28:06 volumio go-librespot[9698]: time="2026-02-17T10:28:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:07 volumio go-librespot[9698]: time="2026-02-17T10:28:07+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:07 volumio go-librespot[9698]: time="2026-02-17T10:28:07+07:00" level=debug msg="completed challenge" Feb 17 10:28:07 volumio go-librespot[9698]: time="2026-02-17T10:28:07+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:07 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:07 volumio volumio[9370]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 17 10:28:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:10 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:10 volumio volumio[9370]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:10 volumio go-librespot[9706]: go-librespot daemon starting... Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=debug msg="app state loaded" Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 10:28:10 volumio volumio[9370]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:10 volumio go-librespot[9707]: time="2026-02-17T10:28:10+07:00" level=info msg="zeroconf server listening on port 45217" Feb 17 10:28:11 volumio go-librespot[9707]: time="2026-02-17T10:28:11+07:00" level=debug msg="obtained new client token: AAC6qXXlFwPGPOtSZsSblfHT2rGdq0tike3Kdv7ifXCcltHfANUKAjm/ll/s34yXvzYb40KrYoSgxvzLAErd7n2GER2L/cGG83FpKVbNrSreDbiT/dexxdVxuSmSS00eRmNK3PHqn7u94i9/fh4vYtYj3Pp0AatD+844IgBHwn+8GgzM6hApf1miP0/CeikpAzN39faSX6wxjd2dN6Ci1t2pTKMEfV2FndN4Ks9cFMrv+2aBQA3WzvQ=" Feb 17 10:28:11 volumio go-librespot[9707]: time="2026-02-17T10:28:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:11 volumio go-librespot[9707]: time="2026-02-17T10:28:11+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:11 volumio go-librespot[9707]: time="2026-02-17T10:28:11+07:00" level=debug msg="completed challenge" Feb 17 10:28:12 volumio volumio[9370]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 10:28:12 volumio volumio[9370]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 10:28:12 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:12 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:12 volumio volumio[9370]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 10:28:12 volumio go-librespot[9707]: time="2026-02-17T10:28:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:12 volumio volumio[9370]: info: MyVolumio login type: Token Feb 17 10:28:12 volumio volumio[9370]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 10:28:12 volumio volumio[9370]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 10:28:14 volumio volumio[9370]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 10:28:14 volumio volumio[9370]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 10:28:14 volumio volumio[9370]: info: Streaming services startup Feb 17 10:28:14 volumio volumio[9370]: info: Starting Streaming Daemon Feb 17 10:28:14 volumio sudo[9731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:28:14 volumio sudo[9731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:14 volumio volumio[9370]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 10:28:14 volumio sudo[9731]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:14 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:14 volumio volumio[9370]: error: Cannot start Volumio Streaming Daemon Feb 17 10:28:14 volumio volumio[9370]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 10:28:14 volumio volumio[9370]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 10:28:14 volumio volumio[9370]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:15 volumio volumio[9370]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 17 10:28:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 17 10:28:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:15 volumio go-librespot[9737]: go-librespot daemon starting... Feb 17 10:28:15 volumio go-librespot[9738]: time="2026-02-17T10:28:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:15 volumio go-librespot[9738]: time="2026-02-17T10:28:15+07:00" level=debug msg="app state loaded" Feb 17 10:28:15 volumio go-librespot[9738]: time="2026-02-17T10:28:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:15 volumio volumio[9370]: info: MyVolumio token set successfully Feb 17 10:28:15 volumio volumio[9370]: info: MYVOLUMIO: Adding device Feb 17 10:28:15 volumio volumio[9370]: info: MYVOLUMIO: Evaluating Server Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=info msg="zeroconf server listening on port 42637" Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="obtained new client token: AADQVyvRutAKWWrIc4BBjE41WJvBlyVAJ2QzkfD0Zv/b129rd6/qByanlZ3CEGV+PMozb+q+FocbWIatlSXR1fy0OgIZyuZwtuiQIMmNPywtxRrLspJXzJYNTItEsFZGA1km2yXQ0VmfxThTiWcalkhYUddlklE40dLxnJo7oDhiWxdRNHi4A6g3H+RmJ1Dc6LCmIh/GGbAJO/P27MI6WXMwDk3Qqcg3kr/PxbIhCilenzaRB0wOhRbv2Q==" Feb 17 10:28:16 volumio volumio[9370]: info: MyVolumio status changed Feb 17 10:28:16 volumio volumio[9370]: info: Streaming services startup Feb 17 10:28:16 volumio volumio[9370]: info: Starting Streaming Daemon Feb 17 10:28:16 volumio volumio[9370]: info: Removing browser output: myVolumio user plan is not superstar Feb 17 10:28:16 volumio volumio[9370]: info: Removing audio output: Feb 17 10:28:16 volumio volumio[9370]: info: Stoppping Tunnel 1 Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:16 volumio sudo[9766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:28:16 volumio sudo[9766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:16 volumio sudo[9766]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=debug msg="completed challenge" Feb 17 10:28:16 volumio sudo[9769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 17 10:28:16 volumio sudo[9769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:16 volumio volumio[9370]: error: Cannot start Volumio Streaming Daemon Feb 17 10:28:16 volumio volumio[9370]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 10:28:16 volumio volumio[9370]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 10:28:16 volumio go-librespot[9738]: time="2026-02-17T10:28:16+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:16 volumio volumio[9370]: info: Setting Geolocation for MyVolumio to as1 Feb 17 10:28:16 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:16 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:16 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 10:28:16 volumio sudo[9769]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:16 volumio volumio[9370]: info: Remote SSH Stopped Feb 17 10:28:17 volumio volumio[9370]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 10:28:17 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:17 volumio volumio[9370]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:17 volumio volumio[9370]: info: Updating MyVolumio device info Feb 17 10:28:17 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:17 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:17 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:18 volumio volumio[9370]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 10:28:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 17 10:28:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:19 volumio go-librespot[9771]: go-librespot daemon starting... Feb 17 10:28:19 volumio go-librespot[9772]: time="2026-02-17T10:28:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:19 volumio go-librespot[9772]: time="2026-02-17T10:28:19+07:00" level=debug msg="app state loaded" Feb 17 10:28:19 volumio go-librespot[9772]: time="2026-02-17T10:28:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:20 volumio volumio[9370]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="new websocket client" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=info msg="zeroconf server listening on port 34561" Feb 17 10:28:20 volumio volumio[9370]: info: Connection to go-librespot Websocket established Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="obtained new client token: AAC0V/81RcHMAlMpVqdCEOM9JzkTh3/ZRy0cA5knB9a/5US1w6J/CdhTSjsB8Q/bn/0tV1Uktx4WgrQCwXVXCjTjhh6bM6tSVBSqPJIv41q0FhjRHFXxOwwnNpRDdfpDJHH3weloDsIOxNRyKitU/O4cevzkIf8t8yU5+XjxrhNa52S6ga41cVmFE6CgWkEv/54tF2qoabL5rpI4RfIKZc7jd/SLh7Vpc8AnFuZFdo6YVVHShhd7l0QOgA==" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=debug msg="completed challenge" Feb 17 10:28:20 volumio go-librespot[9772]: time="2026-02-17T10:28:20+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:20 volumio volumio[9370]: info: Connection to go-librespot Websocket closed Feb 17 10:28:21 volumio volumio[9370]: info: MYVOLUMIO: Adding device Feb 17 10:28:21 volumio volumio[9370]: info: MYVOLUMIO: Evaluating Server Feb 17 10:28:21 volumio volumio[9370]: info: Setting Geolocation for MyVolumio to as1 Feb 17 10:28:21 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:21 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:21 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:22 volumio volumio[9370]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 10:28:22 volumio volumio[9370]: info: Updating MyVolumio device info Feb 17 10:28:22 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:22 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:22 volumio volumio[9370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:23 volumio volumio[9370]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 10:28:23 volumio volumio[9370]: info: Getting Spotify volume Feb 17 10:28:23 volumio volumio[9370]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:28:23 volumio volumio[9370]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:23 volumio volumio[9370]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 10:28:23 volumio volumio[9370]: errno: -111, Feb 17 10:28:23 volumio volumio[9370]: code: 'ECONNREFUSED', Feb 17 10:28:23 volumio volumio[9370]: syscall: 'connect', Feb 17 10:28:23 volumio volumio[9370]: address: '127.0.0.1', Feb 17 10:28:23 volumio volumio[9370]: port: 9879, Feb 17 10:28:23 volumio volumio[9370]: response: undefined Feb 17 10:28:23 volumio volumio[9370]: } Feb 17 10:28:23 volumio volumio[9370]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:28:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 17 10:28:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:24 volumio go-librespot[9823]: go-librespot daemon starting... Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=debug msg="app state loaded" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:24 volumio go-librespot[9824]: time="2026-02-17T10:28:24+07:00" level=info msg="zeroconf server listening on port 40067" Feb 17 10:28:24 volumio sudo[9834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:27' Feb 17 10:28:24 volumio sudo[9834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:25 volumio go-librespot[9824]: time="2026-02-17T10:28:25+07:00" level=debug msg="obtained new client token: AACAjIkxjW/omTseQXsZCWr38DkJPle/euryGetFa6IvIwNr9xyWtSeNyCLXCqk98WX9YlbpFzlLwjTAmOWYuxWn4B8EYusTcmuyhKZVVJxD00I+RzONrxwlkDSBuN5LtqBMZV0/4O45MdcMDDzPkBabwvaBj8soKUnwa0CyIytRXm+6NAYy3o2TmIRazQ1yk+zQVmpfEwXOKVfyOYo/h2UGpGDJt/WFkAnY9G3bgOq5eIX2KfJA9k8pOg==" Feb 17 10:28:25 volumio go-librespot[9824]: time="2026-02-17T10:28:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:25 volumio go-librespot[9824]: time="2026-02-17T10:28:25+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:25 volumio go-librespot[9824]: time="2026-02-17T10:28:25+07:00" level=debug msg="completed challenge" Feb 17 10:28:25 volumio sudo[9834]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:25 volumio go-librespot[9824]: time="2026-02-17T10:28:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:25 volumio volumio-remote-updater[25144]: [2026-02-17 10:28:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 10:28:25 volumio volumio-remote-updater[25144]: [2026-02-17 10:28:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 10:28:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 10:28:25 volumio systemd[1]: volumio.service: Consumed 58.452s CPU time. Feb 17 10:28:25 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:28:25 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:28:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20253. Feb 17 10:28:25 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:28:25 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 10:28:25 volumio systemd[1]: volumio.service: Consumed 58.452s CPU time. Feb 17 10:28:25 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 10:28:25 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:28:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 17 10:28:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:28 volumio go-librespot[9861]: go-librespot daemon starting... Feb 17 10:28:28 volumio go-librespot[9862]: time="2026-02-17T10:28:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:28 volumio go-librespot[9862]: time="2026-02-17T10:28:28+07:00" level=debug msg="app state loaded" Feb 17 10:28:28 volumio go-librespot[9862]: time="2026-02-17T10:28:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=info msg="zeroconf server listening on port 43013" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="obtained new client token: AAC3r9UYyCPxqBezk7ibgfJKGq4552ArL/HPKcIC3knXx88EgJ1TKdRVRUKXhDMD19+/Pp33afgY/rKqF9AqOo4369YOQhmDtzyLaeNx0fdQCWplE6D3tUySDefqJMZqlP20JPad+5Ec+aGjUOZi4fTIjwXmCJx4/QtmrZ7HLkkc8tQMp96ALK+0crGllKwIOvHCdbOSovym7tSs3l+kh5JkDCeW9XQyZo8dFcIXRD9ngGFFHMAJ68Y8hQ==" Feb 17 10:28:29 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:29 volumio volumio[9846]: info: ----- Volumio3 ---- Feb 17 10:28:29 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:29 volumio volumio[9846]: info: ----- System startup ---- Feb 17 10:28:29 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=debug msg="completed challenge" Feb 17 10:28:29 volumio go-librespot[9862]: time="2026-02-17T10:28:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:30 volumio volumio-remote-updater[25144]: [2026-02-17 10:28:30] [connect] Successful connection Feb 17 10:28:30 volumio volumio[9846]: info: MYVOLUMIO Environment detected Feb 17 10:28:31 volumio volumio[9846]: info: Plugin folders cleanup Feb 17 10:28:31 volumio volumio[9846]: info: Scanning into folder /volumio/app/plugins/ Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category audio_interface Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category miscellanea Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category music_service Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category plugins.json Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category system_controller Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category user_interface Feb 17 10:28:31 volumio volumio[9846]: info: Scanning into folder /data/plugins/ Feb 17 10:28:31 volumio volumio[9846]: info: Scanning category music_service Feb 17 10:28:31 volumio volumio[9846]: info: Plugin folders cleanup completed Feb 17 10:28:31 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:31 volumio volumio[9846]: info: ----- Core plugins startup ---- Feb 17 10:28:31 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:31 volumio volumio[9846]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 10:28:31 volumio volumio[9846]: info: Adding plugin upnp to MyMusic Plugins Feb 17 10:28:31 volumio volumio[9846]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 10:28:31 volumio volumio[9846]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 10:28:31 volumio volumio[9846]: info: Loading plugins from folder /data/plugins/ Feb 17 10:28:31 volumio volumio[9846]: info: Loading plugin "system"... Feb 17 10:28:31 volumio volumio[9846]: info: Loading plugin "appearance"... Feb 17 10:28:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 17 10:28:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:32 volumio go-librespot[9882]: go-librespot daemon starting... Feb 17 10:28:32 volumio go-librespot[9883]: time="2026-02-17T10:28:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:32 volumio go-librespot[9883]: time="2026-02-17T10:28:32+07:00" level=debug msg="app state loaded" Feb 17 10:28:32 volumio go-librespot[9883]: time="2026-02-17T10:28:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "network"... Feb 17 10:28:33 volumio volumio[9846]: info: Refreshing Cached IP Addresses Feb 17 10:28:33 volumio sudo[9890]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:28:33 volumio sudo[9890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:33 volumio sudo[9892]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:28:33 volumio sudo[9890]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:33 volumio sudo[9892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:33 volumio sudo[9892]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "services"... Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "alsa_controller"... Feb 17 10:28:33 volumio sudo[9901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 10:28:33 volumio sudo[9901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:33 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "wizard"... Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "networkfs"... Feb 17 10:28:33 volumio volumio[9846]: info: Starting Udev Watcher for removable devices Feb 17 10:28:33 volumio volumio[9846]: info: Ignoring mount for partition: boot Feb 17 10:28:33 volumio volumio[9846]: info: Ignoring mount for partition: volumio Feb 17 10:28:33 volumio volumio[9846]: info: Ignoring mount for partition: volumio_data Feb 17 10:28:33 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "volumio_command_line_client"... Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "upnp"... Feb 17 10:28:33 volumio volumio[9846]: info: [1771298913478] Starting Upmpd Daemon Feb 17 10:28:33 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "my_music"... Feb 17 10:28:33 volumio volumio[9846]: info: Loading plugin "mpd"... Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=info msg="zeroconf server listening on port 35257" Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=debug msg="obtained new client token: AAAdJRey9/FymAbGeuQaJkBpgzH/SQwYKKiMswKEQPuSa03KVNnWlhngyTh5Agnz4NB+IXYHXUcFWx71TwQhCUzupqeBVrWvBotuL4gBMzairu+9lyIzkZ8+z4hcsj0w8yVput+rpdFmdMSOFHMVwsh6FH/bnVCbC0ApLqVvFBIfl1ya3Bttn+8/mhyYrmDgmvxISfq4PNzgtqrfttu7+w8yOxC9q3gZl2TCqsZ1/nSdQiEYQs6s8pvT9g==" Feb 17 10:28:33 volumio go-librespot[9883]: time="2026-02-17T10:28:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:34 volumio go-librespot[9883]: time="2026-02-17T10:28:34+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:34 volumio go-librespot[9883]: time="2026-02-17T10:28:34+07:00" level=debug msg="completed challenge" Feb 17 10:28:34 volumio go-librespot[9883]: time="2026-02-17T10:28:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:34 volumio volumio[9846]: info: Loading plugin "upnp_browser"... Feb 17 10:28:35 volumio sudo[9901]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:37 volumio volumio[9846]: info: Starting UPNP Browser Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "alarm-clock"... Feb 17 10:28:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 17 10:28:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "airplay_emulation"... Feb 17 10:28:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:37 volumio go-librespot[9924]: go-librespot daemon starting... Feb 17 10:28:37 volumio volumio[9846]: info: Starting Shairport Sync Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "last_100"... Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "webradio"... Feb 17 10:28:37 volumio go-librespot[9925]: time="2026-02-17T10:28:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:37 volumio go-librespot[9925]: time="2026-02-17T10:28:37+07:00" level=debug msg="app state loaded" Feb 17 10:28:37 volumio go-librespot[9925]: time="2026-02-17T10:28:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "i2s_dacs"... Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "volumiodiscovery"... Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** For more information see Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:28:37 volumio volumio[9846]: *** WARNING *** For more information see Feb 17 10:28:37 volumio node[9846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:28:37 volumio node[9846]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:28:37 volumio node[9846]: *** WARNING *** For more information see Feb 17 10:28:37 volumio node[9846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:28:37 volumio node[9846]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:28:37 volumio node[9846]: *** WARNING *** For more information see Feb 17 10:28:37 volumio volumio[9846]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 10:28:37 volumio volumio[9846]: info: Discovery: Started advertising with name: Volumio Feb 17 10:28:37 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:28:37 volumio volumio[9846]: info: Loading plugin "spop"... Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=info msg="zeroconf server listening on port 46329" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="obtained new client token: AADQNkkTvyx3dpw0kcocAggSzM3FmUwL+fFvKp988n8VNxPXB4mv318JArXbLU8jnnPfJyKqkYFSMaP0XiBoWU2SYkwPR2CjIu2mZJaGNZkwpSLZxNiM473TTxkJCTHmuwf++S2kg+vjET9I23i6l+9z8COPoB82WA5QtxoZSEpVUtPRtlEQMCjGuCyn6K7xI3i8KNn9ZWjCdZWKbE+t4iD6wLNS7N6WriDd9H134znfwihZna5xA8Gf2Q==" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=debug msg="completed challenge" Feb 17 10:28:38 volumio go-librespot[9925]: time="2026-02-17T10:28:38+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:39 volumio volumio[9846]: info: Loading plugin "ytcr"... Feb 17 10:28:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 17 10:28:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:41 volumio go-librespot[9934]: go-librespot daemon starting... Feb 17 10:28:41 volumio go-librespot[9935]: time="2026-02-17T10:28:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:41 volumio go-librespot[9935]: time="2026-02-17T10:28:41+07:00" level=debug msg="app state loaded" Feb 17 10:28:41 volumio go-librespot[9935]: time="2026-02-17T10:28:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:42 volumio volumio[9846]: info: Loading plugin "ytmusic"... Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=info msg="zeroconf server listening on port 39917" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="obtained new client token: AAAwdPemvIZYxEM4WNnnzy3Fno++ccfeGD3cI2DL/DJM0eDQ6yjr52YpDDhJaJe/lOvz0p3iB2YnMDMcq+ip31xlrLM58v7hVOoOSZ2odu5q2LqOe7Aez+f7opuP/qhqtbHzUpMtwmh7dDfFjIKMAcqcjwCBlpVPXPxgi+FgCyV4oCFTr4J8ozTXiufqOngRAnqJaXOIDr7hFNzD28GyRzd6dLlPS/RNQOa5pLSfmSL7LodPfJgTOofHUQ==" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=debug msg="completed challenge" Feb 17 10:28:42 volumio volumio-remote-updater[25144]: [2026-02-17 10:28:42] [connect] Successful connection Feb 17 10:28:42 volumio go-librespot[9935]: time="2026-02-17T10:28:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "outputs"... Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "albumart"... Feb 17 10:28:43 volumio volumio[9846]: info: Plugin example_plugin is not enabled Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "inputs"... Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "updater_comm"... Feb 17 10:28:43 volumio volumio[9846]: info: Plugin mpdemulation is not enabled Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "rest_api"... Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "websocket"... Feb 17 10:28:43 volumio volumio[9846]: info: Starting Socket.io Server version 1.7.4 Feb 17 10:28:43 volumio volumio[9846]: info: Loading plugin "RoonBridge"... Feb 17 10:28:44 volumio volumio[9846]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 10:28:44 volumio volumio[9846]: info: Loading i18n strings for locale en Feb 17 10:28:44 volumio volumio[9846]: Updating browse sources language Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:44 volumio volumio[9957]: Forking 3 albumart workers Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::initPlayerControls Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:28:44 volumio volumio[9846]: Express server listening on port 3000 Feb 17 10:28:44 volumio volumio[9846]: [Metrics] WebUI: 15s 983.09ms Feb 17 10:28:44 volumio volumio[9846]: info: CoreStateMachine::resetVolumioState Feb 17 10:28:44 volumio volumio[9846]: info: CoreStateMachine::getcurrentVolume Feb 17 10:28:44 volumio volumio[9846]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:28:44 volumio sudo[10006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:28:44 volumio sudo[10004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:28:44 volumio sudo[10006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:44 volumio sudo[10004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:44 volumio sudo[10004]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:44 volumio sudo[10006]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:44 volumio volumio[9846]: info: Volumio Network Manager: Network status updated: 1 Feb 17 10:28:45 volumio volumio[9846]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::pushState Feb 17 10:28:45 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::updateTrackBlock Feb 17 10:28:45 volumio volumio[9846]: info: CorePlayQueue::getTrackBlock Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:28:45 volumio volumio-remote-updater[25144]: [2026-02-17 10:28:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771298922 101 Feb 17 10:28:45 volumio volumio[9846]: 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 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:45 volumio volumio[9846]: info: Reloading queue from file Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::setRepeat null single undefined Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::pushState Feb 17 10:28:45 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::setRandom null Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::pushState Feb 17 10:28:45 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:45 volumio volumio[9846]: info: Setting Device type: Raspberry PI Feb 17 10:28:45 volumio volumio[9846]: info: Completed loading Core Plugins Feb 17 10:28:45 volumio volumio[9846]: info: Preparing to generate the ALSA configuration file Feb 17 10:28:45 volumio volumio[9846]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:28:45 volumio volumio[9846]: info: CoreStateMachine::pushState Feb 17 10:28:45 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:45 volumio volumio[9846]: info: Asound.conf file unchanged, so no further update is needed Feb 17 10:28:45 volumio volumio[9846]: info: Output device has changed, restarting MPD Feb 17 10:28:45 volumio sudo[10023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 10:28:45 volumio sudo[10023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:45 volumio volumio[9846]: info: Output device has changed, restarting Shairport Sync Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:45 volumio sudo[10028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:28:45 volumio sudo[10025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:28:45 volumio sudo[10028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:45 volumio sudo[10025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:45 volumio sudo[10023]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:45 volumio volumio[9846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:28:45 volumio sudo[10025]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:45 volumio volumio[9846]: info: ___________ START PLUGINS ___________ Feb 17 10:28:45 volumio volumio[9846]: info: ControllerMpd::onStart: Initializing MPD Feb 17 10:28:45 volumio volumio[9846]: info: Creating MPD Configuration file Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:28:45 volumio volumio[9846]: info: [1771298925840] CoreMusicLibrary::Adding element Media Servers Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:45 volumio volumio[9846]: info: UPNP Browser: Client initialized successfully Feb 17 10:28:45 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 10:28:45 volumio sudo[10038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:28:45 volumio sudo[10038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:45 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:45 volumio sudo[10036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:28:45 volumio sudo[10036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:45 volumio sudo[10036]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:46 volumio volumio[9846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:46 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:28:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:28:46 volumio systemd[1]: mpd.service: Consumed 7.077s CPU time. Feb 17 10:28:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 17 10:28:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:28:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:28:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:28:46 volumio volumio[9846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:28:46 volumio volumio[9846]: info: [1771298926097] CoreMusicLibrary::Adding element Last_100 Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:28:46 volumio volumio[9846]: info: [1771298926105] CoreMusicLibrary::Adding element Webradio Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:28:46 volumio volumio[9846]: info: Initializing BBC Radios Feb 17 10:28:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:28:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:28:46 volumio go-librespot[10054]: go-librespot daemon starting... Feb 17 10:28:46 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:28:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:28:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:28:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:28:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=debug msg="app state loaded" Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:28:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:28:46 volumio volumio[9846]: info: Creating Spotify config file Feb 17 10:28:46 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:46 volumio sudo[10072]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 10:28:46 volumio sudo[10072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 10:28:46 volumio sudo[10072]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:46 volumio go-librespot[10059]: time="2026-02-17T10:28:46+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=info msg="zeroconf server listening on port 46009" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=debug msg="obtained new client token: AABRwIljRLH/ZjXIWGMhFlKzOvXVar/i2KudXqIjkKBTa02rYeIL6G6xGQLaC26aboF7TplGMNr8Ea4wpF/VOIacFvkzAetxPg/ysHDNPqczt0hrlOjDIaUrceO2UQrhrX1H1GPSo7Tw+Luddfcku9wJ30ZB/wafQGUUtqEfsD0kAm/NYqnJ2IEU0UV0Wl7FELmwRQG6Cm7SFdDwWm4WRwzt6KpsDWRDLIf9WSBGtTUVDMA7w95tjZw=" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=debug msg="completed challenge" Feb 17 10:28:47 volumio go-librespot[10059]: time="2026-02-17T10:28:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:47 volumio volumio[9846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:28:47 volumio volumio[9846]: info: [1771298927923] CoreMusicLibrary::Adding element YouTube Music Feb 17 10:28:47 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:47 volumio volumio[9846]: Cannot find translation for source YouTube Music Feb 17 10:28:47 volumio volumio[9974]: Starting albumart workers Feb 17 10:28:47 volumio volumio[9846]: info: Volumio Calling Home Feb 17 10:28:48 volumio sudo[10078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 10:28:48 volumio sudo[10078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:48 volumio volumio[9973]: Starting albumart workers Feb 17 10:28:48 volumio sudo[10078]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:48 volumio volumio[9972]: Starting albumart workers Feb 17 10:28:48 volumio volumio[9846]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:28:48 volumio volumio[9846]: info: Discovery: Found device Volumio Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:28:48 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:48 volumio volumio[9846]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:28:48 volumio volumio[9846]: info: Discovery: Found device Volumio Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:28:48 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:48 volumio volumio[9846]: info: MPD Permissions set Feb 17 10:28:48 volumio volumio[9846]: info: MPD Permissions set Feb 17 10:28:48 volumio volumio[9846]: info: Upmpdcli Daemon Started Feb 17 10:28:48 volumio volumio[9846]: info: Spotify config file written Feb 17 10:28:48 volumio volumio[9846]: 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 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio sudo[10084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:48 volumio sudo[10084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:48 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:49 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:28:49 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:28:49 volumio volumio[9846]: info: No need to fix Spotify hosts Feb 17 10:28:49 volumio go-librespot[10096]: go-librespot daemon starting... Feb 17 10:28:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:49 volumio sudo[10084]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=debug msg="app state loaded" Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:49 volumio volumio[9846]: info: Volumio called home Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:49 volumio go-librespot[10097]: time="2026-02-17T10:28:49+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=info msg="zeroconf server listening on port 46113" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=debug msg="obtained new client token: AACRD7ZBXMCNgimfeTnNjyuTS1DsK2D5TkcbQl1kwydAWTAUJRXhj9NxPqMcupa0xs1V+7Ahxzr3XJqUsodOOWqu18ZMeBEQnQAPAGuYeJtCy54iqFDwphd3PorpBcVvfRYn9eSKdoF3UFxjuMprbPmKM2JHdsQel4+7wCn3SlIwdFC0UyHVcuaNZNEctCj4CamuaGVxKxvA12K8Hlzombeu5nHh1do/MsfTG0MyK6bna0Oc54BROg0=" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=debug msg="completed challenge" Feb 17 10:28:50 volumio go-librespot[10097]: time="2026-02-17T10:28:50+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:50 volumio volumio[9846]: info: Starting Shairport Sync Feb 17 10:28:50 volumio volumio[9846]: info: Starting Shairport Sync Feb 17 10:28:50 volumio volumio[9846]: info: Starting Shairport Sync Feb 17 10:28:50 volumio sudo[10118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:28:50 volumio sudo[10120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:28:50 volumio sudo[10118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:50 volumio sudo[10120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:50 volumio sudo[10123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:28:50 volumio sudo[10123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:28:50 volumio volumio[9846]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:28:50 volumio volumio[9846]: SPOTIFY: BQCqH0M0SM7wGu36iV2AdSZrc8CrqP7cgn9m_KoD8ZLoBrbJqpkA7Sn8U7fE0K_btGIg-mDoG5gsqZCm5qJFvrFzM7criZUnInHKZ94cPbW0O4IDmmgvE-r0QqIChEew_AJeMDJHt6cJYQznOlKU2fxHuH_pOEZYpR0pBKG3lSU3eoMenn-577R416HHCHY6B4pn7Kof2It1ETWqKahRXM7C5k_v7MF199VZpEmkVRjmH24QpKb8cJGgsOYkI6ljE5m9XMwIearhRbqk_jl9E9i3Sy8WcF_3CTumZYz8-lutN1Hf4WaSbxyW Feb 17 10:28:50 volumio volumio[9846]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:28:50 volumio volumio[9846]: info: New Spotify access token = BQCqH0M0SM7wGu36iV2AdSZrc8CrqP7cgn9m_KoD8ZLoBrbJqpkA7Sn8U7fE0K_btGIg-mDoG5gsqZCm5qJFvrFzM7criZUnInHKZ94cPbW0O4IDmmgvE-r0QqIChEew_AJeMDJHt6cJYQznOlKU2fxHuH_pOEZYpR0pBKG3lSU3eoMenn-577R416HHCHY6B4pn7Kof2It1ETWqKahRXM7C5k_v7MF199VZpEmkVRjmH24QpKb8cJGgsOYkI6ljE5m9XMwIearhRbqk_jl9E9i3Sy8WcF_3CTumZYz8-lutN1Hf4WaSbxyW Feb 17 10:28:50 volumio volumio[9846]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 10:28:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 10:28:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 10:28:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:28:51 volumio systemd[1]: shairport-sync.service: Consumed 2.355s CPU time. Feb 17 10:28:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:28:51 volumio sudo[10118]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:51 volumio sudo[10120]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:51 volumio sudo[10123]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:51 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:28:51 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:51 volumio volumio[9846]: info: Shairport-Sync Started Feb 17 10:28:51 volumio volumio[9846]: Error adding Membership: Error: addMembership EINVAL Feb 17 10:28:51 volumio volumio[9846]: info: Shairport-Sync Started Feb 17 10:28:51 volumio volumio[9846]: info: Shairport-Sync Started Feb 17 10:28:51 volumio volumio[9846]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 17 10:28:51 volumio volumio[9846]: info: Spotify Successfully logged in Feb 17 10:28:51 volumio volumio[9846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:28:51 volumio volumio[9846]: info: [1771298931616] CoreMusicLibrary::Adding element Spotify Feb 17 10:28:51 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:28:51 volumio volumio[9846]: Cannot find translation for source YouTube Music Feb 17 10:28:51 volumio volumio[9846]: Cannot find translation for source Spotify Feb 17 10:28:52 volumio volumio[9846]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 10:28:52 volumio volumio[9846]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:28:52 volumio volumio[9846]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:28:52 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:28:52 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:52 volumio volumio[9846]: info: CoreStateMachine::pushState Feb 17 10:28:52 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:28:52 volumio volumio[9846]: info: CoreCommandRouter::volumioPushState Feb 17 10:28:53 volumio volumio[9846]: info: go-librespot daemon successfully initialized Feb 17 10:28:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 17 10:28:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:53 volumio go-librespot[10164]: go-librespot daemon starting... Feb 17 10:28:53 volumio go-librespot[10165]: time="2026-02-17T10:28:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:53 volumio go-librespot[10165]: time="2026-02-17T10:28:53+07:00" level=debug msg="app state loaded" Feb 17 10:28:53 volumio go-librespot[10165]: time="2026-02-17T10:28:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:54 volumio mpd[10076]: 2026-02-17T10:28:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 10:28:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 10:28:54 volumio sudo[10028]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:54 volumio sudo[10038]: pam_unix(sudo:session): session closed for user root Feb 17 10:28:54 volumio volumio[9846]: error: MPD error: The expression evaluated to a falsy value: Feb 17 10:28:54 volumio volumio[9846]: assert.ok(self.idling) Feb 17 10:28:54 volumio volumio[9846]: error: The expression evaluated to a falsy value: Feb 17 10:28:54 volumio volumio[9846]: assert.ok(self.idling) Feb 17 10:28:54 volumio volumio[9846]: error: updateQueue error: null Feb 17 10:28:54 volumio volumio[9846]: info: MPD running with PID10076 Feb 17 10:28:54 volumio volumio[9846]: ,establishing connection Feb 17 10:28:54 volumio volumio[9846]: info: Completed starting Core Plugins Feb 17 10:28:54 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:54 volumio volumio[9846]: info: ----- MyVolumio plugins startup ---- Feb 17 10:28:54 volumio volumio[9846]: info: ------------------------------------------- Feb 17 10:28:54 volumio volumio[9846]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 10:28:54 volumio volumio[9846]: error: updateQueue error: null Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=info msg="zeroconf server listening on port 38451" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="obtained new client token: AAD5iacvRL2jShDri4jyycaRXHZGCxVOs3N4k/ji8pRrMGamllg/Bg/gzVmSA+Azyz3s00ueFsfwojgPQiJVLRuBos9IDPk71/fe+T8NJNCZwszxpA/OdUdyHm3B0EcQrjRxa4DTufbG+ZUq+ClNmhby101SlxOwO0WgALMI/ICxbirN4AsHmVj4CPsEMo48Ph69T5YKBillP2elC7CWKPUkF6mpJkGiHgA7s2BDMbYdlYmtgeS4n2JDbQ==" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:54 volumio go-librespot[10165]: time="2026-02-17T10:28:54+07:00" level=debug msg="completed challenge" Feb 17 10:28:55 volumio go-librespot[10165]: time="2026-02-17T10:28:55+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:56 volumio volumio[9846]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:56 volumio volumio[9846]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:28:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 17 10:28:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:28:58 volumio go-librespot[10177]: go-librespot daemon starting... Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=debug msg="app state loaded" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=info msg="zeroconf server listening on port 44267" Feb 17 10:28:58 volumio go-librespot[10178]: time="2026-02-17T10:28:58+07:00" level=debug msg="obtained new client token: AAB43i9tzAVpdG7iIeQzqafNeB2gTXc5CohyJBmLTxLQMsmhmJLYFqt9nv1HSuiY7Ocb7dsXXt/tfRRS2xi57i0Imd6d3cQuZbUDM+NL1OaH8nEDej3oHqG0ebrsLJKycs5mquAPHL6KzOE1CvWYWHu0KIWdHS7W0BZ+/+EuqCDEbGVksmj4hbrZunxSEdKWpOlgQTn9iReHJdPbt10mpqPWons+zpdC+wD3X0ktWgP0Jzxrm4cJ8kgIpg==" Feb 17 10:28:59 volumio go-librespot[10178]: time="2026-02-17T10:28:59+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:28:59 volumio go-librespot[10178]: time="2026-02-17T10:28:59+07:00" level=debug msg="completed keyexchange" Feb 17 10:28:59 volumio go-librespot[10178]: time="2026-02-17T10:28:59+07:00" level=debug msg="completed challenge" Feb 17 10:28:59 volumio volumio[9846]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:28:59 volumio go-librespot[10178]: time="2026-02-17T10:28:59+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:28:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:28:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:28:59 volumio volumio[9846]: info: Initializing connection to go-librespot Websocket Feb 17 10:28:59 volumio volumio[9846]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:29:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 17 10:29:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:02 volumio go-librespot[10185]: go-librespot daemon starting... Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=debug msg="app state loaded" Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:02 volumio volumio[9846]: info: Initializing connection to go-librespot Websocket Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=debug msg="new websocket client" Feb 17 10:29:02 volumio volumio[9846]: info: Connection to go-librespot Websocket established Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:02 volumio go-librespot[10186]: time="2026-02-17T10:29:02+07:00" level=info msg="zeroconf server listening on port 42489" Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 10:29:03 volumio go-librespot[10186]: time="2026-02-17T10:29:03+07:00" level=debug msg="obtained new client token: AAA7boG/dG5NRF1SzZMfpk7JRzkaJPgvq9Bjx/K4LnknC3KZYYvp5xahDKH7kOCdc68iedGfCxFoEMzW7cnJtgusHwzg2EfV9UZdQCpKhrjuz19lqup11Z2AoweMVGtZVjJ1R4X98m7xJmGSO2xK3dh0h5o0iXJqpywT+nFIU5fIUvqDFfvWznMAOIgG4XFJZ8C29zLHE/q6uM17CTfosNwbsRI7w6QZ2fUJDxvAvmBmUEqfRgjIiBw=" Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 10:29:03 volumio volumio[9846]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 10:29:03 volumio go-librespot[10186]: time="2026-02-17T10:29:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:03 volumio go-librespot[10186]: time="2026-02-17T10:29:03+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:03 volumio go-librespot[10186]: time="2026-02-17T10:29:03+07:00" level=debug msg="completed challenge" Feb 17 10:29:03 volumio go-librespot[10186]: time="2026-02-17T10:29:03+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:05 volumio volumio[9846]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 10:29:05 volumio volumio[9846]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 10:29:05 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:05 volumio volumio[9846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:05 volumio volumio[9846]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 10:29:05 volumio volumio[9846]: info: MyVolumio login type: Token Feb 17 10:29:05 volumio volumio[9846]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 10:29:05 volumio volumio[9846]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 10:29:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 17 10:29:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:06 volumio go-librespot[10207]: go-librespot daemon starting... Feb 17 10:29:06 volumio go-librespot[10208]: time="2026-02-17T10:29:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:06 volumio go-librespot[10208]: time="2026-02-17T10:29:06+07:00" level=debug msg="app state loaded" Feb 17 10:29:06 volumio go-librespot[10208]: time="2026-02-17T10:29:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:06 volumio volumio[9846]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 10:29:06 volumio volumio[9846]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 10:29:06 volumio volumio[9846]: info: Streaming services startup Feb 17 10:29:06 volumio volumio[9846]: info: Starting Streaming Daemon Feb 17 10:29:06 volumio sudo[10215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:29:07 volumio sudo[10215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:07 volumio volumio[9846]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 10:29:07 volumio sudo[10215]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:07 volumio volumio[9846]: info: Getting Spotify volume Feb 17 10:29:07 volumio volumio[9846]: info: Connection to go-librespot Websocket closed Feb 17 10:29:07 volumio volumio[9846]: error: Cannot start Volumio Streaming Daemon Feb 17 10:29:07 volumio volumio[9846]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 10:29:07 volumio volumio[9846]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 10:29:07 volumio volumio[9846]: 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 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=info msg="zeroconf server listening on port 45623" Feb 17 10:29:07 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:07 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:07 volumio volumio[9846]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 10:29:07 volumio volumio[9846]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 10:29:07 volumio volumio[9846]: SPOTIFY: VOLUMIO VOLUME 100 Feb 17 10:29:07 volumio volumio[9846]: info: Aligning Spotify Volume to Volumio Volume Feb 17 10:29:07 volumio volumio[9846]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:07 volumio volumio[9846]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:07 volumio volumio[9846]: info: Setting Spotify Volume from Volumio: 100 Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="obtained new client token: AABT2vBoeX18BDGtH7YaQs/jotp/e+Mo8ydCPSqRP3R7YRv0dm75gvC+2/sqV9atKqNJxCqcXBuDRuZg9womgfJuZpZ5Glsh8ztVwp97UDP6rlTnNkbuy5Yd5JCd23/C1t+VgidjjaBMSLhb1UrD58F184wczhk8HIdR16v9tz+1QHqDznCxpUqT9dOY9MDenPl+RlgfHrKRf49zUu7ikT3tNUJeBChxY4VveaXMQ8UuAG+P8SllgXhMrA==" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=debug msg="completed challenge" Feb 17 10:29:07 volumio go-librespot[10208]: time="2026-02-17T10:29:07+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:07 volumio volumio[9846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:29:07 volumio volumio[9846]: Error: socket hang up Feb 17 10:29:07 volumio volumio[9846]: at connResetException (node:internal/errors:720:14) Feb 17 10:29:07 volumio volumio[9846]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 10:29:07 volumio volumio[9846]: at Socket.emit (node:events:526:35) Feb 17 10:29:07 volumio volumio[9846]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 10:29:07 volumio volumio[9846]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 10:29:07 volumio volumio[9846]: code: 'ECONNRESET', Feb 17 10:29:07 volumio volumio[9846]: response: undefined Feb 17 10:29:07 volumio volumio[9846]: } Feb 17 10:29:07 volumio volumio[9846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:29:09 volumio sudo[10237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:28' Feb 17 10:29:09 volumio sudo[10237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:09 volumio sudo[10237]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:11 volumio volumio-remote-updater[25144]: [2026-02-17 10:29:11] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 17 10:29:11 volumio volumio-remote-updater[25144]: [2026-02-17 10:29:11] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 17 10:29:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 17 10:29:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:11 volumio go-librespot[10246]: go-librespot daemon starting... Feb 17 10:29:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 10:29:11 volumio systemd[1]: volumio.service: Consumed 55.598s CPU time. Feb 17 10:29:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=debug msg="app state loaded" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:29:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20254. Feb 17 10:29:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:29:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 10:29:11 volumio systemd[1]: volumio.service: Consumed 55.598s CPU time. Feb 17 10:29:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 10:29:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=info msg="zeroconf server listening on port 33541" Feb 17 10:29:11 volumio go-librespot[10252]: time="2026-02-17T10:29:11+07:00" level=debug msg="obtained new client token: AAAeijtIi3Ip0i8m+/+AAUnZ3sY6TZ1fWG9YjpMVuFBBcFQcGbkUYrKtN4TwLuYhlW6RpGf7Mwo/pjTiWmG5aq/YgE/5Lt4WA66tgPKkGFVtEoGu7vBegYDSwatBuu2yUQauA3yf+KwFyQQTWWbEfWkedwz1ytlv4StKH4R38lv68ZV7p8DqhF9AKYt+yzA5kxQcB0+de6XAnXKBs7DSk8ipeg9n5NMuo3zid9k3vbQeCDNc5bDrf59BQA==" Feb 17 10:29:12 volumio go-librespot[10252]: time="2026-02-17T10:29:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:12 volumio go-librespot[10252]: time="2026-02-17T10:29:12+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:12 volumio go-librespot[10252]: time="2026-02-17T10:29:12+07:00" level=debug msg="completed challenge" Feb 17 10:29:12 volumio go-librespot[10252]: time="2026-02-17T10:29:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:15 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:15 volumio volumio[10265]: info: ----- Volumio3 ---- Feb 17 10:29:15 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:15 volumio volumio[10265]: info: ----- System startup ---- Feb 17 10:29:15 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 17 10:29:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:15 volumio go-librespot[10288]: go-librespot daemon starting... Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=debug msg="app state loaded" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:15 volumio go-librespot[10289]: time="2026-02-17T10:29:15+07:00" level=info msg="zeroconf server listening on port 35839" Feb 17 10:29:16 volumio volumio-remote-updater[25144]: [2026-02-17 10:29:16] [connect] Successful connection Feb 17 10:29:16 volumio go-librespot[10289]: time="2026-02-17T10:29:16+07:00" level=debug msg="obtained new client token: AACo9OthKotGPHOFS1j0SXSYP79WnVnmQvKJzE82vQUPBlnlD0lP44AXRPJbronm9WCYbwoevCALnPsA2gkWwlGVsQHKJdTYtXQ1M5QZ8Rx38wlXiWTjpSE6Wlazx6E0EOyf10iTeIqrDFhz+2tnKlnUcDdd13DQCsrj1PiSwwIUwclLu8fgk25RS7vbRd1uJoC1XDqHUXvcKV7GHC/uOuJul6jwlvOiT7eaayy+6qpEpJJpNfFr5lA=" Feb 17 10:29:16 volumio go-librespot[10289]: time="2026-02-17T10:29:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:16 volumio volumio[10265]: info: MYVOLUMIO Environment detected Feb 17 10:29:16 volumio go-librespot[10289]: time="2026-02-17T10:29:16+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:16 volumio go-librespot[10289]: time="2026-02-17T10:29:16+07:00" level=debug msg="completed challenge" Feb 17 10:29:16 volumio go-librespot[10289]: time="2026-02-17T10:29:16+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:16 volumio volumio[10265]: info: Plugin folders cleanup Feb 17 10:29:16 volumio volumio[10265]: info: Scanning into folder /volumio/app/plugins/ Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category audio_interface Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category miscellanea Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category music_service Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category plugins.json Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category system_controller Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category user_interface Feb 17 10:29:16 volumio volumio[10265]: info: Scanning into folder /data/plugins/ Feb 17 10:29:16 volumio volumio[10265]: info: Scanning category music_service Feb 17 10:29:16 volumio volumio[10265]: info: Plugin folders cleanup completed Feb 17 10:29:16 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:16 volumio volumio[10265]: info: ----- Core plugins startup ---- Feb 17 10:29:16 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:16 volumio volumio[10265]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 10:29:16 volumio volumio[10265]: info: Adding plugin upnp to MyMusic Plugins Feb 17 10:29:16 volumio volumio[10265]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 10:29:16 volumio volumio[10265]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 10:29:16 volumio volumio[10265]: info: Loading plugins from folder /data/plugins/ Feb 17 10:29:16 volumio volumio[10265]: info: Loading plugin "system"... Feb 17 10:29:16 volumio volumio[10265]: info: Loading plugin "appearance"... Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "network"... Feb 17 10:29:18 volumio volumio[10265]: info: Refreshing Cached IP Addresses Feb 17 10:29:18 volumio sudo[10304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:29:18 volumio sudo[10304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:18 volumio sudo[10306]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:29:18 volumio sudo[10304]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "services"... Feb 17 10:29:18 volumio sudo[10306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "alsa_controller"... Feb 17 10:29:18 volumio sudo[10306]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:18 volumio sudo[10314]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 10:29:18 volumio sudo[10314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:18 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "wizard"... Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "networkfs"... Feb 17 10:29:18 volumio volumio[10265]: info: Starting Udev Watcher for removable devices Feb 17 10:29:18 volumio volumio[10265]: info: Ignoring mount for partition: boot Feb 17 10:29:18 volumio volumio[10265]: info: Ignoring mount for partition: volumio Feb 17 10:29:18 volumio volumio[10265]: info: Ignoring mount for partition: volumio_data Feb 17 10:29:18 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:29:18 volumio volumio[10265]: info: Loading plugin "volumio_command_line_client"... Feb 17 10:29:19 volumio volumio[10265]: info: Loading plugin "upnp"... Feb 17 10:29:19 volumio volumio[10265]: info: [1771298959013] Starting Upmpd Daemon Feb 17 10:29:19 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:29:19 volumio volumio[10265]: info: Loading plugin "my_music"... Feb 17 10:29:19 volumio volumio[10265]: info: Loading plugin "mpd"... Feb 17 10:29:19 volumio volumio[10265]: info: Loading plugin "upnp_browser"... Feb 17 10:29:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 17 10:29:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:19 volumio go-librespot[10337]: go-librespot daemon starting... Feb 17 10:29:19 volumio go-librespot[10338]: time="2026-02-17T10:29:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:19 volumio go-librespot[10338]: time="2026-02-17T10:29:19+07:00" level=debug msg="app state loaded" Feb 17 10:29:19 volumio go-librespot[10338]: time="2026-02-17T10:29:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=info msg="zeroconf server listening on port 39613" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="obtained new client token: AAD7VALB/vQJghQOoIUyKOBh3ddsHWYJYMbvBbWvsjyZHEhsSFTRdX8SnN1SLr7qtLE5w/I4JAjYlONOnks49fluJ+7NVm2QONs2BOd/ijFayqnxd44pLs1/i3tkz7aoKSOWH9swpSbEfcO/F421t3TyD2EXOo2CvWZ/jfADu7oD79lo9jWExsmjMNnx7SEDOa9t3xI1O6f9wi3WZr1YvGSp8UaxOyDXrB0UToXpLDhuOzxzXw8h8D8p2w==" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=debug msg="completed challenge" Feb 17 10:29:20 volumio go-librespot[10338]: time="2026-02-17T10:29:20+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:21 volumio sudo[10314]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:22 volumio volumio[10265]: info: Starting UPNP Browser Feb 17 10:29:22 volumio volumio[10265]: info: Loading plugin "alarm-clock"... Feb 17 10:29:22 volumio volumio[10265]: info: Loading plugin "airplay_emulation"... Feb 17 10:29:22 volumio volumio[10265]: info: Starting Shairport Sync Feb 17 10:29:22 volumio volumio[10265]: info: Loading plugin "last_100"... Feb 17 10:29:22 volumio volumio[10265]: info: Loading plugin "webradio"... Feb 17 10:29:23 volumio volumio[10265]: info: Loading plugin "i2s_dacs"... Feb 17 10:29:23 volumio volumio[10265]: info: Loading plugin "volumiodiscovery"... Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:29:23 volumio node[10265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** For more information see Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:29:23 volumio volumio[10265]: *** WARNING *** For more information see Feb 17 10:29:23 volumio node[10265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:29:23 volumio node[10265]: *** WARNING *** For more information see Feb 17 10:29:23 volumio node[10265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:29:23 volumio node[10265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:29:23 volumio node[10265]: *** WARNING *** For more information see Feb 17 10:29:23 volumio volumio[10265]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 10:29:23 volumio volumio[10265]: info: Discovery: Started advertising with name: Volumio Feb 17 10:29:23 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:29:23 volumio volumio[10265]: info: Loading plugin "spop"... Feb 17 10:29:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 17 10:29:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:24 volumio go-librespot[10347]: go-librespot daemon starting... Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=debug msg="app state loaded" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=info msg="zeroconf server listening on port 40071" Feb 17 10:29:24 volumio go-librespot[10348]: time="2026-02-17T10:29:24+07:00" level=debug msg="obtained new client token: AADTv5TVWCi1t03tlSPIfOeoDgrd8gCFgNCSxvnTgnx7XNprljuyyjkgMX3F/qI00oMQ7kR0uQRTwyUfG3ITJxtTTzcQpJdJbr1j1RfW1/aXnOHH4ugaz28rib8Tic3jckmkhZgpzmiBYhrLh3vAp5Wm6h2Zbsc6c0p1CTiegzN8O24qUhDuUejxty8p7lZ5W+W89KiUupK5xu9tKFj+gQ+sDNLhA3hURoUR+ndbL8L2i1QyyQA2Ht9euA==" Feb 17 10:29:25 volumio go-librespot[10348]: time="2026-02-17T10:29:25+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 10:29:25 volumio go-librespot[10348]: time="2026-02-17T10:29:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:29:25 volumio volumio[10265]: info: Loading plugin "ytcr"... Feb 17 10:29:25 volumio go-librespot[10348]: time="2026-02-17T10:29:25+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:25 volumio go-librespot[10348]: time="2026-02-17T10:29:25+07:00" level=debug msg="completed challenge" Feb 17 10:29:25 volumio go-librespot[10348]: time="2026-02-17T10:29:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:27 volumio volumio[10265]: info: Loading plugin "ytmusic"... Feb 17 10:29:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 17 10:29:28 volumio volumio-remote-updater[25144]: [2026-02-17 10:29:28] [connect] Successful connection Feb 17 10:29:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:28 volumio go-librespot[10355]: go-librespot daemon starting... Feb 17 10:29:28 volumio go-librespot[10356]: time="2026-02-17T10:29:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:28 volumio go-librespot[10356]: time="2026-02-17T10:29:28+07:00" level=debug msg="app state loaded" Feb 17 10:29:28 volumio go-librespot[10356]: time="2026-02-17T10:29:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:28 volumio volumio[10265]: info: Loading plugin "outputs"... Feb 17 10:29:28 volumio volumio[10265]: info: Loading plugin "albumart"... Feb 17 10:29:29 volumio volumio[10265]: info: Plugin example_plugin is not enabled Feb 17 10:29:29 volumio volumio[10265]: info: Loading plugin "inputs"... Feb 17 10:29:29 volumio volumio[10265]: info: Loading plugin "updater_comm"... Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=info msg="zeroconf server listening on port 39591" Feb 17 10:29:29 volumio volumio[10265]: info: Plugin mpdemulation is not enabled Feb 17 10:29:29 volumio volumio[10265]: info: Loading plugin "rest_api"... Feb 17 10:29:29 volumio volumio[10265]: info: Loading plugin "websocket"... Feb 17 10:29:29 volumio volumio[10265]: info: Starting Socket.io Server version 1.7.4 Feb 17 10:29:29 volumio volumio[10265]: info: Loading plugin "RoonBridge"... Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="obtained new client token: AACFjp61CUSlmln+pIy/4X5UJjl1wQETOBKJAWluuCdQyqXO2ixAVrnryKfl89J0aMEtAJy9EqFXDET9kkhX4woomDrb1lzNgUWsd7YRlpAL3GdJjhLgHjtXqrQDMtIfD9wjw/++7y3BR6KeJB3SIBIRAkOol7EfqQzCIY770MLqOZL9OPk2gF77XU45rzTDMVul3pK0EDnC9gAVF7t0NTmcGT3EEf+KtYUTLB05AsQswx7tUoYVlRFueQ==" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=debug msg="completed challenge" Feb 17 10:29:29 volumio go-librespot[10356]: time="2026-02-17T10:29:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:29 volumio volumio[10265]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 10:29:29 volumio volumio[10265]: info: Loading i18n strings for locale en Feb 17 10:29:29 volumio volumio[10265]: Updating browse sources language Feb 17 10:29:29 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:30 volumio nmbd[966]: [2026/02/17 10:29:30.168241, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 10:29:30 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 17 10:29:30 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 17 10:29:30 volumio volumio[10376]: Forking 3 albumart workers Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::initPlayerControls Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:29:30 volumio volumio[10265]: Express server listening on port 3000 Feb 17 10:29:30 volumio volumio[10265]: [Metrics] WebUI: 16s 148.60ms Feb 17 10:29:30 volumio volumio[10265]: info: CoreStateMachine::resetVolumioState Feb 17 10:29:30 volumio volumio[10265]: info: CoreStateMachine::getcurrentVolume Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:29:30 volumio sudo[10421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:29:30 volumio sudo[10421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:30 volumio sudo[10421]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:30 volumio sudo[10424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:29:30 volumio sudo[10424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:30 volumio volumio[10265]: info: Volumio Network Manager: Network status updated: 1 Feb 17 10:29:30 volumio sudo[10424]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:30 volumio volumio[10265]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:29:30 volumio volumio[10265]: info: CoreStateMachine::pushState Feb 17 10:29:30 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::volumioPushState Feb 17 10:29:30 volumio volumio[10265]: info: CoreStateMachine::updateTrackBlock Feb 17 10:29:30 volumio volumio[10265]: info: CorePlayQueue::getTrackBlock Feb 17 10:29:30 volumio volumio[10265]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:29:30 volumio volumio-remote-updater[25144]: [2026-02-17 10:29:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771298968 101 Feb 17 10:29:30 volumio volumio[10265]: 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 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:31 volumio volumio[10265]: info: Reloading queue from file Feb 17 10:29:31 volumio volumio[10265]: info: CoreStateMachine::setRepeat null single undefined Feb 17 10:29:31 volumio volumio[10265]: info: CoreStateMachine::pushState Feb 17 10:29:31 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::volumioPushState Feb 17 10:29:31 volumio volumio[10265]: info: CoreStateMachine::setRandom null Feb 17 10:29:31 volumio volumio[10265]: info: CoreStateMachine::pushState Feb 17 10:29:31 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::volumioPushState Feb 17 10:29:31 volumio volumio[10265]: info: Setting Device type: Raspberry PI Feb 17 10:29:31 volumio volumio[10265]: info: Completed loading Core Plugins Feb 17 10:29:31 volumio volumio[10265]: info: Preparing to generate the ALSA configuration file Feb 17 10:29:31 volumio volumio[10265]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:29:31 volumio volumio[10265]: info: CoreStateMachine::pushState Feb 17 10:29:31 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::volumioPushState Feb 17 10:29:31 volumio volumio[10265]: info: Asound.conf file unchanged, so no further update is needed Feb 17 10:29:31 volumio volumio[10265]: info: Output device has changed, restarting MPD Feb 17 10:29:31 volumio sudo[10440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 10:29:31 volumio sudo[10440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:31 volumio volumio[10265]: info: Output device has changed, restarting Shairport Sync Feb 17 10:29:31 volumio sudo[10442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:29:31 volumio sudo[10442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:31 volumio sudo[10442]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:31 volumio sudo[10445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:29:31 volumio sudo[10445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:31 volumio sudo[10440]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:31 volumio volumio[10265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:29:31 volumio volumio[10265]: info: ___________ START PLUGINS ___________ Feb 17 10:29:31 volumio volumio[10265]: info: ControllerMpd::onStart: Initializing MPD Feb 17 10:29:31 volumio volumio[10265]: info: Creating MPD Configuration file Feb 17 10:29:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:29:31 volumio volumio[10265]: info: [1771298971649] CoreMusicLibrary::Adding element Media Servers Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:31 volumio volumio[10265]: info: UPNP Browser: Client initialized successfully Feb 17 10:29:31 volumio sudo[10454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:29:31 volumio sudo[10454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:31 volumio sudo[10454]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:31 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:29:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:29:31 volumio systemd[1]: mpd.service: Consumed 7.168s CPU time. Feb 17 10:29:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:29:31 volumio sudo[10455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:29:31 volumio sudo[10455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:29:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:29:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:29:31 volumio volumio[10265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:31 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:29:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:29:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:29:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:29:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:29:31 volumio volumio[10265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:29:31 volumio volumio[10265]: info: [1771298971994] CoreMusicLibrary::Adding element Last_100 Feb 17 10:29:31 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:29:32 volumio volumio[10265]: info: [1771298972009] CoreMusicLibrary::Adding element Webradio Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:29:32 volumio volumio[10265]: info: Initializing BBC Radios Feb 17 10:29:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:29:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:32 volumio volumio[10265]: info: Creating Spotify config file Feb 17 10:29:32 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:32 volumio sudo[10474]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 10:29:32 volumio sudo[10474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 10:29:32 volumio sudo[10474]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 17 10:29:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:33 volumio go-librespot[10485]: go-librespot daemon starting... Feb 17 10:29:33 volumio volumio[10391]: Starting albumart workers Feb 17 10:29:33 volumio go-librespot[10486]: time="2026-02-17T10:29:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:33 volumio volumio[10389]: Starting albumart workers Feb 17 10:29:33 volumio volumio[10265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:29:33 volumio volumio[10265]: info: [1771298973723] CoreMusicLibrary::Adding element YouTube Music Feb 17 10:29:33 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:33 volumio volumio[10265]: Cannot find translation for source YouTube Music Feb 17 10:29:33 volumio volumio[10265]: info: Volumio Calling Home Feb 17 10:29:33 volumio sudo[10494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 10:29:33 volumio sudo[10494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:33 volumio go-librespot[10486]: time="2026-02-17T10:29:33+07:00" level=info msg="zeroconf server listening on port 42545" Feb 17 10:29:34 volumio volumio[10390]: Starting albumart workers Feb 17 10:29:34 volumio sudo[10494]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:34 volumio volumio[10265]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:29:34 volumio volumio[10265]: info: Discovery: Found device Volumio Feb 17 10:29:34 volumio volumio[10265]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:34 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:34 volumio volumio[10265]: info: MPD Permissions set Feb 17 10:29:34 volumio volumio[10265]: info: MPD Permissions set Feb 17 10:29:34 volumio volumio[10265]: info: Upmpdcli Daemon Started Feb 17 10:29:34 volumio volumio[10265]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:29:34 volumio volumio[10265]: info: Discovery: Found device Volumio Feb 17 10:29:34 volumio volumio[10265]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:34 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:34 volumio volumio[10265]: info: Volumio called home Feb 17 10:29:34 volumio volumio[10265]: info: Spotify config file written Feb 17 10:29:34 volumio sudo[10500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 10:29:34 volumio sudo[10500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:34 volumio volumio[10265]: 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 17 10:29:34 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:34 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:34 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 10:29:35 volumio systemd[1]: go-librespot-daemon.service: Killing process 10491 (go-librespot) with signal SIGKILL. Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 10:29:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:35 volumio go-librespot[10502]: go-librespot daemon starting... Feb 17 10:29:35 volumio sudo[10500]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=debug msg="app state loaded" Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:29:35 volumio volumio[10265]: info: No need to fix Spotify hosts Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:35 volumio go-librespot[10509]: time="2026-02-17T10:29:35+07:00" level=info msg="zeroconf server listening on port 35607" Feb 17 10:29:36 volumio go-librespot[10509]: time="2026-02-17T10:29:36+07:00" level=debug msg="obtained new client token: AADE6elXMYerOPA5SS8rbjezdII6QIJIC/xx4D3fgRm52haA5m49cWj15GGFUKTwTjmVBREG3awH7gOLapU6grfeQ6Ta406ijLBxBDKwpB5gc4Z6K4F2qgb5gZGeYOwHO+1DMQoKP+wvaoigXjkQun2p2wGaH+Tm8e8YS+DU6KCKiIsl7lauIp0kkyjfW6JG8O0H056OZ4E6WL6Qv56vHcPaIERwymzCjsmHYV6RSJA8qPa7IcHnylI=" Feb 17 10:29:36 volumio go-librespot[10509]: time="2026-02-17T10:29:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:36 volumio volumio[10265]: info: Starting Shairport Sync Feb 17 10:29:36 volumio volumio[10265]: info: Starting Shairport Sync Feb 17 10:29:36 volumio volumio[10265]: info: Starting Shairport Sync Feb 17 10:29:36 volumio go-librespot[10509]: time="2026-02-17T10:29:36+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:36 volumio go-librespot[10509]: time="2026-02-17T10:29:36+07:00" level=debug msg="completed challenge" Feb 17 10:29:36 volumio sudo[10541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:29:36 volumio sudo[10541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:36 volumio sudo[10543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:29:36 volumio sudo[10543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:36 volumio sudo[10545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:29:36 volumio sudo[10545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:36 volumio go-librespot[10509]: time="2026-02-17T10:29:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 10:29:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 10:29:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:29:36 volumio systemd[1]: shairport-sync.service: Consumed 2.311s CPU time. Feb 17 10:29:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:29:36 volumio sudo[10545]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:36 volumio sudo[10543]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:36 volumio volumio[10265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:29:36 volumio volumio[10265]: SPOTIFY: BQDhw4URA1lsR7RumrzpWtzAiLHSypH3jFVU2aq_LPsYArMJYN1I1ZHPyMGbtGx-SIhhYFcRLkmHHE3V4v51VsJ8XDpkUHO2ytRHmypn1JzFHUlXCfAvZnVKBFEughe2DRCgQZK-mlSbNCNHx1dsK0vGFv1H-CXBzIVXX29z6Hf1nj2UCC6O_UGJgqEqEsEGAYO-uYGJ7j2UIkIqI91ylO_OKrS9uYkjn0gREMcdzURFPGAGzcfU3ExUNSnKuMBRJZpFxE0-aqe8DlpGn9jkTUSro2WIvEPPXOBZtjaFkDGWgFUqQIhTQicr Feb 17 10:29:36 volumio volumio[10265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:29:36 volumio sudo[10541]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:36 volumio volumio[10265]: info: New Spotify access token = BQDhw4URA1lsR7RumrzpWtzAiLHSypH3jFVU2aq_LPsYArMJYN1I1ZHPyMGbtGx-SIhhYFcRLkmHHE3V4v51VsJ8XDpkUHO2ytRHmypn1JzFHUlXCfAvZnVKBFEughe2DRCgQZK-mlSbNCNHx1dsK0vGFv1H-CXBzIVXX29z6Hf1nj2UCC6O_UGJgqEqEsEGAYO-uYGJ7j2UIkIqI91ylO_OKrS9uYkjn0gREMcdzURFPGAGzcfU3ExUNSnKuMBRJZpFxE0-aqe8DlpGn9jkTUSro2WIvEPPXOBZtjaFkDGWgFUqQIhTQicr Feb 17 10:29:36 volumio volumio[10265]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 10:29:36 volumio volumio[10265]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:36 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:36 volumio volumio[10265]: info: Shairport-Sync Started Feb 17 10:29:36 volumio volumio[10265]: Error adding Membership: Error: addMembership EINVAL Feb 17 10:29:36 volumio volumio[10265]: info: Shairport-Sync Started Feb 17 10:29:36 volumio volumio[10265]: info: Shairport-Sync Started Feb 17 10:29:37 volumio volumio[10265]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 17 10:29:37 volumio volumio[10265]: info: Spotify Successfully logged in Feb 17 10:29:37 volumio volumio[10265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:29:37 volumio volumio[10265]: info: [1771298977352] CoreMusicLibrary::Adding element Spotify Feb 17 10:29:37 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:29:37 volumio volumio[10265]: Cannot find translation for source YouTube Music Feb 17 10:29:37 volumio volumio[10265]: Cannot find translation for source Spotify Feb 17 10:29:37 volumio volumio[10265]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 10:29:37 volumio volumio[10265]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:29:38 volumio volumio[10265]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:29:38 volumio volumio[10265]: info: CoreCommandRouter::volumioGetState Feb 17 10:29:38 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:38 volumio volumio[10265]: info: CoreStateMachine::pushState Feb 17 10:29:38 volumio volumio[10265]: info: CorePlayQueue::getTrack 0 Feb 17 10:29:38 volumio volumio[10265]: info: CoreCommandRouter::volumioPushState Feb 17 10:29:39 volumio volumio[10265]: info: go-librespot daemon successfully initialized Feb 17 10:29:39 volumio mpd[10484]: 2026-02-17T10:29:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 10:29:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 10:29:39 volumio sudo[10455]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:39 volumio sudo[10445]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 10:29:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:39 volumio go-librespot[10586]: go-librespot daemon starting... Feb 17 10:29:39 volumio go-librespot[10587]: time="2026-02-17T10:29:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:39 volumio go-librespot[10587]: time="2026-02-17T10:29:39+07:00" level=debug msg="app state loaded" Feb 17 10:29:39 volumio go-librespot[10587]: time="2026-02-17T10:29:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:39 volumio volumio[10265]: error: MPD error: The expression evaluated to a falsy value: Feb 17 10:29:39 volumio volumio[10265]: assert.ok(self.idling) Feb 17 10:29:39 volumio volumio[10265]: error: The expression evaluated to a falsy value: Feb 17 10:29:39 volumio volumio[10265]: assert.ok(self.idling) Feb 17 10:29:39 volumio volumio[10265]: info: MPD running with PID10484 Feb 17 10:29:39 volumio volumio[10265]: ,establishing connection Feb 17 10:29:39 volumio volumio[10265]: error: updateQueue error: null Feb 17 10:29:39 volumio volumio[10265]: info: Completed starting Core Plugins Feb 17 10:29:39 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:39 volumio volumio[10265]: info: ----- MyVolumio plugins startup ---- Feb 17 10:29:39 volumio volumio[10265]: info: ------------------------------------------- Feb 17 10:29:39 volumio volumio[10265]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 10:29:39 volumio volumio[10265]: error: updateQueue error: null Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=info msg="zeroconf server listening on port 33613" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="obtained new client token: AAB6D4mDfuYFfPWpMche2XKHP4qs+CsPlPCf2kxO9Qs69NkJv5NxU/27kjPJpkOikoYbKHzqPgzYGexQSAamMQcUV+mQeh7x4tJVyR5JnPi49i0Y1qLkfxBn+W6HtT4SN6dCsKVzYft9gw0CPMtQArzdYGH7QAU1VlAwvsdiI6VOIabM5FVMbrbhxkIqc5ypHZodCKLvxu/vLI2e68/Xl8TteavNlLaITBhqTiZyfWMTpKpeyISHQF40Yw==" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=debug msg="completed challenge" Feb 17 10:29:40 volumio go-librespot[10587]: time="2026-02-17T10:29:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:42 volumio volumio[10265]: info: Initializing connection to go-librespot Websocket Feb 17 10:29:42 volumio volumio[10265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:29:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 10:29:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:44 volumio go-librespot[10594]: go-librespot daemon starting... Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="app state loaded" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=info msg="zeroconf server listening on port 42837" Feb 17 10:29:44 volumio volumio[10265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="obtained new client token: AADgtUFvhvNPZJluZiVdZdYxX2CzW9PG+ezwcykhxMho1kjSt+eD0kHUMdk5oP5/XzZpJl9bJnyXiIZg3K4d5xoOJt6uoQh3tYuj5nTCGxBRvTUpng59pGyhKsfC/4ICG00MUkHPsoJUbk8QIbK/LPWddKewwyWa4fwIEMZ9Y1h5EZMsiSkMO/6T+r5iY14boEaLh9cQau8AEcR0vzIFcAibGk7DNaXUcuPV2FZUzhAskb3qBEDqIYaXLg==" Feb 17 10:29:44 volumio go-librespot[10595]: time="2026-02-17T10:29:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:45 volumio go-librespot[10595]: time="2026-02-17T10:29:45+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:45 volumio go-librespot[10595]: time="2026-02-17T10:29:45+07:00" level=debug msg="completed challenge" Feb 17 10:29:45 volumio go-librespot[10595]: time="2026-02-17T10:29:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:45 volumio volumio[10265]: info: Initializing connection to go-librespot Websocket Feb 17 10:29:45 volumio volumio[10265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:29:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 10:29:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:48 volumio go-librespot[10605]: go-librespot daemon starting... Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=debug msg="app state loaded" Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:48 volumio volumio[10265]: info: Initializing connection to go-librespot Websocket Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=debug msg="new websocket client" Feb 17 10:29:48 volumio volumio[10265]: info: Connection to go-librespot Websocket established Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 10:29:48 volumio volumio[10265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:48 volumio go-librespot[10606]: time="2026-02-17T10:29:48+07:00" level=info msg="zeroconf server listening on port 34267" Feb 17 10:29:49 volumio go-librespot[10606]: time="2026-02-17T10:29:49+07:00" level=debug msg="obtained new client token: AAAEjq0PiAxF1FIfqsXWAzM3+Z5jBVHyj8gY3Xjln9jDxt5Mc23j6wMGLXcMq/QfgVWz/b/nzvVkg7gN470du/NizY+mfV4vXCc8ox3Z4K2pgrd0COKnc1jhnzlObv8uVD5srHdv05+TZgP5GCBHbsORxfo7qZmX+nMi7VmudBWuZAuHjng2jcYGDE+cKoqqwZ1yqHw6sk/4HrcI7iL4PCVXAo8QK71svD4NalZAraklEVBiQnfUUHE=" Feb 17 10:29:49 volumio go-librespot[10606]: time="2026-02-17T10:29:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:29:49 volumio go-librespot[10606]: time="2026-02-17T10:29:49+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:49 volumio go-librespot[10606]: time="2026-02-17T10:29:49+07:00" level=debug msg="completed challenge" Feb 17 10:29:49 volumio go-librespot[10606]: time="2026-02-17T10:29:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:50 volumio volumio[10265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 10:29:50 volumio volumio[10265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 10:29:50 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:50 volumio volumio[10265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:29:50 volumio volumio[10265]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 10:29:50 volumio volumio[10265]: info: MyVolumio login type: Token Feb 17 10:29:50 volumio volumio[10265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 10:29:50 volumio volumio[10265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 10:29:52 volumio volumio[10265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 10:29:52 volumio volumio[10265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 10:29:52 volumio volumio[10265]: info: Streaming services startup Feb 17 10:29:52 volumio volumio[10265]: info: Starting Streaming Daemon Feb 17 10:29:52 volumio sudo[10630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:29:52 volumio sudo[10630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:29:52 volumio volumio[10265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 10:29:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 10:29:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:52 volumio sudo[10630]: pam_unix(sudo:session): session closed for user root Feb 17 10:29:52 volumio volumio[10265]: info: Getting Spotify volume Feb 17 10:29:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:29:52 volumio go-librespot[10636]: go-librespot daemon starting... Feb 17 10:29:52 volumio volumio[10265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:29:52 volumio volumio[10265]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:29:52 volumio volumio[10265]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 10:29:52 volumio volumio[10265]: errno: -111, Feb 17 10:29:52 volumio volumio[10265]: code: 'ECONNREFUSED', Feb 17 10:29:52 volumio volumio[10265]: syscall: 'connect', Feb 17 10:29:52 volumio volumio[10265]: address: '127.0.0.1', Feb 17 10:29:52 volumio volumio[10265]: port: 9879, Feb 17 10:29:52 volumio volumio[10265]: response: undefined Feb 17 10:29:52 volumio volumio[10265]: } Feb 17 10:29:52 volumio volumio[10265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:29:52 volumio go-librespot[10637]: time="2026-02-17T10:29:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:29:52 volumio go-librespot[10637]: time="2026-02-17T10:29:52+07:00" level=debug msg="app state loaded" Feb 17 10:29:52 volumio go-librespot[10637]: time="2026-02-17T10:29:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=info msg="zeroconf server listening on port 38107" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="obtained new client token: AAAFTHASalt1eWmNkgfCol7slPt2rCmvHMOUPJbpwKUB5Amm00b/aUKxXvBwVHrPRv7PZ8JS6QiWTaAh75UFG0nl9adyliLt28GhhA/zggfD0COCMRpdI3fMgngu2bdd8YRWfRx/iE+q0Iy/MdrUvdDb47aa5Q6ibqjFmIJ2o2j2TPTGjGE8DCDO50zYLGzGIYX4HqREJh+KkKMf+uKvdxTEpsYHdrpSvRANBL0s8PHuZZZMu60ejiWuJg==" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="completed keyexchange" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=debug msg="completed challenge" Feb 17 10:29:53 volumio go-librespot[10637]: time="2026-02-17T10:29:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:29:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:29:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:29:54 volumio sudo[10658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:28' Feb 17 10:29:54 volumio sudo[10658]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"