Feb 13 01:17:00 volumio volumio[6662]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:01 volumio volumio[6662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 01:17:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:02 volumio go-librespot[6995]: go-librespot daemon starting... Feb 13 01:17:02 volumio go-librespot[6996]: time="2026-02-13T01:17:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:02 volumio go-librespot[6996]: time="2026-02-13T01:17:02+07:00" level=debug msg="app state loaded" Feb 13 01:17:02 volumio go-librespot[6996]: time="2026-02-13T01:17:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+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 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+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 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+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 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+07:00" level=info msg="zeroconf server listening on port 42073" Feb 13 01:17:03 volumio volumio[6662]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+07:00" level=debug msg="obtained new client token: AAAdwwuB5heFyUgXpNPurLy594RZu8yX9gzm4T6J4KHSDoIhT52OgAMAt9x0MxTs2KyFJBHWZ30FkmsLRDV1uMv33Ls42Zeubmn65RN2wyPLwjvGJtK4lJt/Q/bedewm3aZgIXyFr3kgEFpZqXptsCQFZa5lkNcyv7g/rfQ3Y7y0zToXLuxynbZKbyu3I3SnfyXqu+n6wAovT6t5PeiXRLfZhEV/8vaNXnSsKu1JTmgvJUkr3Hq0CBlXQw==" Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17:03+07:00" level=debug msg="completed challenge" Feb 13 01:17:03 volumio go-librespot[6996]: time="2026-02-13T01:17: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 13 01:17:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:04 volumio volumio[6662]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:04 volumio volumio[6662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 01:17:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:06 volumio go-librespot[7003]: go-librespot daemon starting... Feb 13 01:17:06 volumio go-librespot[7004]: time="2026-02-13T01:17:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:06 volumio go-librespot[7004]: time="2026-02-13T01:17:06+07:00" level=debug msg="app state loaded" Feb 13 01:17:06 volumio go-librespot[7004]: time="2026-02-13T01:17:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:07 volumio volumio[6662]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17:07+07:00" level=debug msg="new websocket client" Feb 13 01:17:07 volumio volumio[6662]: info: Connection to go-librespot Websocket established Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 01:17:07 volumio volumio[6662]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17: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 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17: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 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17: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 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17:07+07:00" level=info msg="zeroconf server listening on port 38629" Feb 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17:07+07:00" level=debug msg="obtained new client token: AABvqJyatX5SnjACrZiYDavKhchgroVQI1IxH9CQbGJsgLSEuoQuf0MBjpKFuKZQhbWe6wM8AxlGxiIa1TNJYZUGjK73hpcvFH00LzQ7WCyXfk401YxttHvA54dQTnaAy5wv+40IGMtvyZpnxRxpMIor7T7kglqrUeASZmNXzOIe17vSGa+rgn83kbRAh4Iv3it0JvV9ZVRZ0rhnJUv+BBSspa7vN0Ju5sKWQhLIyHIM9LKl7/W53gR1/Q==" Feb 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:07 volumio go-librespot[7004]: time="2026-02-13T01:17:07+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:17:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:09 volumio volumio[6662]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 01:17:09 volumio volumio[6662]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 01:17:09 volumio volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:09 volumio volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:09 volumio volumio[6662]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 01:17:09 volumio volumio[6662]: info: MyVolumio login type: Token Feb 13 01:17:09 volumio volumio[6662]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 01:17:09 volumio volumio[6662]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 01:17:10 volumio volumio[6662]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 01:17:10 volumio volumio[6662]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 01:17:10 volumio volumio[6662]: info: Streaming services startup Feb 13 01:17:10 volumio volumio[6662]: info: Starting Streaming Daemon Feb 13 01:17:10 volumio volumio[6662]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 01:17:10 volumio sudo[7027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 01:17:10 volumio sudo[7027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 01:17:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:11 volumio volumio[6662]: info: Getting Spotify volume Feb 13 01:17:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:11 volumio go-librespot[7033]: go-librespot daemon starting... Feb 13 01:17:11 volumio volumio[6662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:17:11 volumio sudo[7027]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:11 volumio volumio[6662]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:11 volumio volumio[6662]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 01:17:11 volumio volumio[6662]: errno: -111, Feb 13 01:17:11 volumio volumio[6662]: code: 'ECONNREFUSED', Feb 13 01:17:11 volumio volumio[6662]: syscall: 'connect', Feb 13 01:17:11 volumio volumio[6662]: address: '127.0.0.1', Feb 13 01:17:11 volumio volumio[6662]: port: 9879, Feb 13 01:17:11 volumio volumio[6662]: response: undefined Feb 13 01:17:11 volumio volumio[6662]: } Feb 13 01:17:11 volumio volumio[6662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+07:00" level=debug msg="app state loaded" Feb 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+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 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+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 13 01:17:11 volumio go-librespot[7034]: time="2026-02-13T01:17:11+07:00" level=info msg="zeroconf server listening on port 40467" Feb 13 01:17:12 volumio go-librespot[7034]: time="2026-02-13T01:17:12+07:00" level=debug msg="obtained new client token: AACAbybZHwqIoCItVQSxZnEo9+PVCscQSlCSwComFE2xECd9iTHJYxRUYlwPRC7RNvvY+XpnMlsK8XjJx8va9cRplL+GyzBpANWIPSaAR/JBXOIyVkdBt/taAxotNyR17T9SfARxsQ/jQoul3Cub3wFSeOOvbPQ8DIAlSPfmbjy8mc51yWfchrMdwscITr1PoD9N5lqjNY6DZySzDUN9kGdcMIr8rRX0M+fnl1sOY1VcYsFMRKQ7sKyIqA==" Feb 13 01:17:12 volumio go-librespot[7034]: time="2026-02-13T01:17:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:12 volumio go-librespot[7034]: time="2026-02-13T01:17:12+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:12 volumio go-librespot[7034]: time="2026-02-13T01:17:12+07:00" level=debug msg="completed challenge" Feb 13 01:17:12 volumio go-librespot[7034]: time="2026-02-13T01:17: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 13 01:17:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:12 volumio sudo[7055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 01:16' Feb 13 01:17:12 volumio sudo[7055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:12 volumio sudo[7055]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:14 volumio volumio-remote-updater[643]: [2026-02-13 01:17:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 01:17:14 volumio volumio-remote-updater[643]: [2026-02-13 01:17:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 01:17:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 01:17:14 volumio systemd[1]: volumio.service: Consumed 55.367s CPU time. Feb 13 01:17:14 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 01:17:14 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 01:17:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14035. Feb 13 01:17:14 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 01:17:14 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 01:17:14 volumio systemd[1]: volumio.service: Consumed 55.367s CPU time. Feb 13 01:17:14 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 01:17:14 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 01:17:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 01:17:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:15 volumio go-librespot[7088]: go-librespot daemon starting... Feb 13 01:17:15 volumio go-librespot[7089]: time="2026-02-13T01:17:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:15 volumio go-librespot[7089]: time="2026-02-13T01:17:15+07:00" level=debug msg="app state loaded" Feb 13 01:17:15 volumio go-librespot[7089]: time="2026-02-13T01:17:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+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 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+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 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=info msg="zeroconf server listening on port 42937" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=debug msg="obtained new client token: AACp6BQM7+5dRwF5DWJdW9USZNwvbveWWjG7gQcDfEKlJmy4Levh/ZuEdNx8zGM8iDxBCXMPFk7xSncCsv2UXc3Iy8Ye85vxlai2BqQN3RQ3eC7d5MUhpt2RlDnLe6HW2CKmLVJHopIChXoZHaSDsGnr5bM7EcCn8booCQEcm0vOfSRFBY5u/RF/6ot81q4wF4Khze9RZDJnu9tKFWwuFYOOIpTK4HoP2Fw59k1W7FFAGp++MARhdSn1Mg==" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+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 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17:16+07:00" level=debug msg="completed challenge" Feb 13 01:17:16 volumio go-librespot[7089]: time="2026-02-13T01:17: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 13 01:17:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:18 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:18 volumio volumio[7073]: info: ----- Volumio3 ---- Feb 13 01:17:18 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:18 volumio volumio[7073]: info: ----- System startup ---- Feb 13 01:17:18 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:19 volumio volumio-remote-updater[643]: [2026-02-13 01:17:19] [connect] Successful connection Feb 13 01:17:19 volumio volumio[7073]: info: MYVOLUMIO Environment detected Feb 13 01:17:19 volumio volumio[7073]: info: Plugin folders cleanup Feb 13 01:17:19 volumio volumio[7073]: info: Scanning into folder /volumio/app/plugins/ Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category audio_interface Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category miscellanea Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category music_service Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category plugins.json Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category system_controller Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category user_interface Feb 13 01:17:19 volumio volumio[7073]: info: Scanning into folder /data/plugins/ Feb 13 01:17:19 volumio volumio[7073]: info: Scanning category music_service Feb 13 01:17:19 volumio volumio[7073]: info: Plugin folders cleanup completed Feb 13 01:17:19 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:19 volumio volumio[7073]: info: ----- Core plugins startup ---- Feb 13 01:17:19 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:19 volumio volumio[7073]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 01:17:19 volumio volumio[7073]: info: Adding plugin upnp to MyMusic Plugins Feb 13 01:17:19 volumio volumio[7073]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 01:17:19 volumio volumio[7073]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 01:17:19 volumio volumio[7073]: info: Loading plugins from folder /data/plugins/ Feb 13 01:17:19 volumio volumio[7073]: info: Loading plugin "system"... Feb 13 01:17:19 volumio volumio[7073]: info: Loading plugin "appearance"... Feb 13 01:17:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 01:17:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:20 volumio go-librespot[7108]: go-librespot daemon starting... Feb 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17:20+07:00" level=debug msg="app state loaded" Feb 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17: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 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17: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 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17: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 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17:20+07:00" level=info msg="zeroconf server listening on port 36095" Feb 13 01:17:20 volumio go-librespot[7109]: time="2026-02-13T01:17:20+07:00" level=debug msg="obtained new client token: AACuXP3lfrFU7KxUIH2eExi1e7AtcCF6vDycxoSgV0ecjOQAMsy69ZNGz0xhsmksLRYllC4QjrVQpQ3qiqS3Mv+ewwXFtzcnBFeewDb5HGSiFmT7HxnGxAz1cUPJriivaFfbZL4Ds0Q70rEFa+YRyBnntc4cqoH3lxnVdTN8BJGB5G1Q8KayQWCaVVI2zXzemz2dBg2ZJ2UNt47RqQUH+fQpOC+iSM6ddiCOpm6KztSquVgYEoMp49K2Vg==" Feb 13 01:17:21 volumio go-librespot[7109]: time="2026-02-13T01:17:21+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 13 01:17:21 volumio go-librespot[7109]: time="2026-02-13T01:17:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 01:17:21 volumio go-librespot[7109]: time="2026-02-13T01:17:21+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:21 volumio go-librespot[7109]: time="2026-02-13T01:17:21+07:00" level=debug msg="completed challenge" Feb 13 01:17:21 volumio go-librespot[7109]: time="2026-02-13T01:17:21+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 13 01:17:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:21 volumio volumio[7073]: info: Loading plugin "network"... Feb 13 01:17:21 volumio volumio[7073]: info: Refreshing Cached IP Addresses Feb 13 01:17:21 volumio sudo[7117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 01:17:21 volumio sudo[7117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:21 volumio volumio[7073]: info: Loading plugin "services"... Feb 13 01:17:21 volumio sudo[7119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 01:17:21 volumio sudo[7119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:21 volumio sudo[7117]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:21 volumio sudo[7119]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:21 volumio volumio[7073]: info: Loading plugin "alsa_controller"... Feb 13 01:17:21 volumio sudo[7127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 01:17:21 volumio sudo[7127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:22 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "wizard"... Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "networkfs"... Feb 13 01:17:22 volumio volumio[7073]: info: Starting Udev Watcher for removable devices Feb 13 01:17:22 volumio volumio[7073]: info: Ignoring mount for partition: boot Feb 13 01:17:22 volumio volumio[7073]: info: Ignoring mount for partition: volumio Feb 13 01:17:22 volumio volumio[7073]: info: Ignoring mount for partition: volumio_data Feb 13 01:17:22 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "volumio_command_line_client"... Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "upnp"... Feb 13 01:17:22 volumio volumio[7073]: info: [1770920242246] Starting Upmpd Daemon Feb 13 01:17:22 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "my_music"... Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "mpd"... Feb 13 01:17:22 volumio volumio[7073]: info: Loading plugin "upnp_browser"... Feb 13 01:17:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 01:17:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:24 volumio go-librespot[7150]: go-librespot daemon starting... Feb 13 01:17:24 volumio go-librespot[7151]: time="2026-02-13T01:17:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:24 volumio go-librespot[7151]: time="2026-02-13T01:17:24+07:00" level=debug msg="app state loaded" Feb 13 01:17:24 volumio go-librespot[7151]: time="2026-02-13T01:17:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:24 volumio sudo[7127]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+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 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+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 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+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 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+07:00" level=info msg="zeroconf server listening on port 35449" Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+07:00" level=debug msg="obtained new client token: AADuU8hhe/l9PFdm8uqCf4xIddbtwYreWMPaOG7KKvsRrMXdSUdhQshQmA1tA1ogaB+KPVqL5QJ/msA7qqA0ysVw7pl99h+E/sB3m9PUASC0RK4nVIo07B/oazfywifULRGx9LEIi/e4Sk7v25BfIGJVUymSEsS4+aAq1xhGIreQFgPoTKFRDeRwwCunwrB2cGQ6r9fKiAMA2H/uyIDhTOGk+5p92g314Q3/5UclWtIWLRTVK4zCp8UokA==" Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17:25+07:00" level=debug msg="completed challenge" Feb 13 01:17:25 volumio go-librespot[7151]: time="2026-02-13T01:17: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 13 01:17:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:25 volumio volumio[7073]: info: Starting UPNP Browser Feb 13 01:17:25 volumio volumio[7073]: info: Loading plugin "alarm-clock"... Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "airplay_emulation"... Feb 13 01:17:26 volumio volumio[7073]: info: Starting Shairport Sync Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "last_100"... Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "webradio"... Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "i2s_dacs"... Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "volumiodiscovery"... Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:17:26 volumio node[7073]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** For more information see Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:17:26 volumio volumio[7073]: *** WARNING *** For more information see Feb 13 01:17:26 volumio node[7073]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:17:26 volumio node[7073]: *** WARNING *** For more information see Feb 13 01:17:26 volumio node[7073]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 01:17:26 volumio node[7073]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:17:26 volumio node[7073]: *** WARNING *** For more information see Feb 13 01:17:26 volumio volumio[7073]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 01:17:26 volumio volumio[7073]: info: Discovery: Started advertising with name: Volumio Feb 13 01:17:26 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:17:26 volumio volumio[7073]: info: Loading plugin "spop"... Feb 13 01:17:28 volumio volumio[7073]: info: Loading plugin "ytcr"... Feb 13 01:17:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 01:17:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:28 volumio go-librespot[7161]: go-librespot daemon starting... Feb 13 01:17:28 volumio go-librespot[7162]: time="2026-02-13T01:17:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:28 volumio go-librespot[7162]: time="2026-02-13T01:17:28+07:00" level=debug msg="app state loaded" Feb 13 01:17:28 volumio go-librespot[7162]: time="2026-02-13T01:17:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+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 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+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 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+07:00" level=info msg="zeroconf server listening on port 38371" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+07:00" level=debug msg="obtained new client token: AAAyNUJqshYWpuq0q1mMNwRSy4Z8SbyM3s65mvneiPM2fZPqch3d++GTcy912OMrR5ffiwt+vk2lMuFMd+BxmzExvpMOCbwXxRPr35CVsrMrSh+IPkpFXJReBewLudGC8L9wd8hyCH0sXgzZcMTwEd8mxRk6U1T9QlIYZNozBbObmTjc7bzQtuQ7NaV79CEiFduG/Rw22y5c5bbZ0FHLqafnpchyhQTFwgV0oQOPu9n8rAfEN/yDo2VuBQ==" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:29 volumio go-librespot[7162]: time="2026-02-13T01:17:29+07:00" level=debug msg="completed challenge" Feb 13 01:17:30 volumio go-librespot[7162]: time="2026-02-13T01:17:30+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 13 01:17:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:31 volumio volumio[7073]: info: Loading plugin "ytmusic"... Feb 13 01:17:31 volumio volumio-remote-updater[643]: [2026-02-13 01:17:31] [connect] Successful connection Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "outputs"... Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "albumart"... Feb 13 01:17:32 volumio volumio[7073]: info: Plugin example_plugin is not enabled Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "inputs"... Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "updater_comm"... Feb 13 01:17:32 volumio volumio[7073]: info: Plugin mpdemulation is not enabled Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "rest_api"... Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "websocket"... Feb 13 01:17:32 volumio volumio[7073]: info: Starting Socket.io Server version 1.7.4 Feb 13 01:17:32 volumio volumio[7073]: info: Loading plugin "RoonBridge"... Feb 13 01:17:32 volumio volumio[7073]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 01:17:32 volumio volumio[7073]: info: Loading i18n strings for locale en Feb 13 01:17:32 volumio volumio[7073]: Updating browse sources language Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:33 volumio volumio[7183]: Forking 3 albumart workers Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::initPlayerControls Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:17:33 volumio volumio[7073]: Express server listening on port 3000 Feb 13 01:17:33 volumio volumio[7073]: [Metrics] WebUI: 15s 911.24ms Feb 13 01:17:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 01:17:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:33 volumio volumio[7073]: info: CoreStateMachine::resetVolumioState Feb 13 01:17:33 volumio volumio[7073]: info: CoreStateMachine::getcurrentVolume Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:17:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:33 volumio go-librespot[7200]: go-librespot daemon starting... Feb 13 01:17:33 volumio go-librespot[7227]: time="2026-02-13T01:17:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:33 volumio go-librespot[7227]: time="2026-02-13T01:17:33+07:00" level=debug msg="app state loaded" Feb 13 01:17:33 volumio go-librespot[7227]: time="2026-02-13T01:17:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:33 volumio sudo[7228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 01:17:33 volumio sudo[7234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 01:17:33 volumio sudo[7234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:33 volumio sudo[7228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:33 volumio sudo[7234]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:33 volumio sudo[7228]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:33 volumio volumio[7073]: info: Volumio Network Manager: Network status updated: 1 Feb 13 01:17:33 volumio volumio[7073]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:17:33 volumio volumio[7073]: info: CoreStateMachine::pushState Feb 13 01:17:33 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::volumioPushState Feb 13 01:17:33 volumio volumio[7073]: info: CoreStateMachine::updateTrackBlock Feb 13 01:17:33 volumio volumio[7073]: info: CorePlayQueue::getTrackBlock Feb 13 01:17:33 volumio volumio[7073]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:17:34 volumio volumio-remote-updater[643]: [2026-02-13 01:17:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770920251 101 Feb 13 01:17:34 volumio volumio[7073]: 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 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:34 volumio volumio[7073]: info: Reloading queue from file Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+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 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+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 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+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 13 01:17:34 volumio volumio[7073]: info: CoreStateMachine::setRepeat null single undefined Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+07:00" level=info msg="zeroconf server listening on port 44895" Feb 13 01:17:34 volumio volumio[7073]: info: CoreStateMachine::pushState Feb 13 01:17:34 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::volumioPushState Feb 13 01:17:34 volumio volumio[7073]: info: CoreStateMachine::setRandom null Feb 13 01:17:34 volumio volumio[7073]: info: CoreStateMachine::pushState Feb 13 01:17:34 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::volumioPushState Feb 13 01:17:34 volumio volumio[7073]: info: Setting Device type: Raspberry PI Feb 13 01:17:34 volumio volumio[7073]: info: Completed loading Core Plugins Feb 13 01:17:34 volumio volumio[7073]: info: Preparing to generate the ALSA configuration file Feb 13 01:17:34 volumio volumio[7073]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:17:34 volumio volumio[7073]: info: CoreStateMachine::pushState Feb 13 01:17:34 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::volumioPushState Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+07:00" level=debug msg="obtained new client token: AADksmknIFDrDZGdP14TKOzEZsqYaoF3ByNahlJeS1H/MEXq7LcwFXoVDOckEeVIc4Ow9AY2kYaF1nxOOsRWI0Ra17jN8vzO6SDbboi8OcBIhEwrRZ2c/1pTucyJpAZ5M7AWEOWZKB7rAqSNkSpoeDaeAy+CxqELdjs160oi/Caqh8QUKVj1w4iQIUdTsUbL72xltwi0zOcE74in8+W7WFkdULioM3LSB8cJw29mUZxWisLqyZuBBTEKvg==" Feb 13 01:17:34 volumio volumio[7073]: info: Asound.conf file unchanged, so no further update is needed Feb 13 01:17:34 volumio volumio[7073]: info: Output device has changed, restarting MPD Feb 13 01:17:34 volumio sudo[7253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 01:17:34 volumio sudo[7253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:34 volumio sudo[7256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 01:17:34 volumio sudo[7256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:34 volumio volumio[7073]: info: Output device has changed, restarting Shairport Sync Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:34 volumio sudo[7256]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:34 volumio sudo[7253]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:34 volumio sudo[7259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17:34+07:00" level=debug msg="completed challenge" Feb 13 01:17:34 volumio sudo[7259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:34 volumio volumio[7073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:17:34 volumio volumio[7073]: info: ___________ START PLUGINS ___________ Feb 13 01:17:34 volumio volumio[7073]: info: ControllerMpd::onStart: Initializing MPD Feb 13 01:17:34 volumio volumio[7073]: info: Creating MPD Configuration file Feb 13 01:17:34 volumio go-librespot[7227]: time="2026-02-13T01:17: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 13 01:17:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 01:17:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:17:34 volumio volumio[7073]: info: [1770920254935] CoreMusicLibrary::Adding element Media Servers Feb 13 01:17:34 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 01:17:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 01:17:35 volumio systemd[1]: mpd.service: Consumed 7.145s CPU time. Feb 13 01:17:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 01:17:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 01:17:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 01:17:35 volumio volumio[7073]: info: UPNP Browser: Client initialized successfully Feb 13 01:17:35 volumio sudo[7268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 01:17:35 volumio sudo[7268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:35 volumio sudo[7266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 01:17:35 volumio sudo[7266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:35 volumio sudo[7266]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 01:17:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 01:17:35 volumio volumio[7073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 01:17:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 01:17:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 01:17:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 01:17:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 01:17:35 volumio volumio[7073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:17:35 volumio volumio[7073]: info: [1770920255280] CoreMusicLibrary::Adding element Last_100 Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:17:35 volumio volumio[7073]: info: [1770920255300] CoreMusicLibrary::Adding element Webradio Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 01:17:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:17:35 volumio volumio[7073]: info: Initializing BBC Radios Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:35 volumio volumio[7073]: info: Creating Spotify config file Feb 13 01:17:35 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:35 volumio sudo[7282]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 01:17:35 volumio sudo[7282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 01:17:35 volumio sudo[7282]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:36 volumio volumio[7073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:17:36 volumio volumio[7073]: info: [1770920256616] CoreMusicLibrary::Adding element YouTube Music Feb 13 01:17:36 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:36 volumio volumio[7073]: Cannot find translation for source YouTube Music Feb 13 01:17:36 volumio volumio[7073]: info: Volumio Calling Home Feb 13 01:17:36 volumio volumio[7196]: Starting albumart workers Feb 13 01:17:36 volumio sudo[7299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 01:17:36 volumio sudo[7299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:36 volumio volumio[7195]: Starting albumart workers Feb 13 01:17:36 volumio sudo[7299]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:37 volumio volumio[7194]: Starting albumart workers Feb 13 01:17:37 volumio volumio[7073]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 01:17:37 volumio volumio[7073]: info: Discovery: Found device Volumio Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::volumioGetState Feb 13 01:17:37 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:37 volumio volumio[7073]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 01:17:37 volumio volumio[7073]: info: Discovery: Found device Volumio Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::volumioGetState Feb 13 01:17:37 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:37 volumio volumio[7073]: info: MPD Permissions set Feb 13 01:17:37 volumio volumio[7073]: info: MPD Permissions set Feb 13 01:17:37 volumio volumio[7073]: info: Upmpdcli Daemon Started Feb 13 01:17:37 volumio volumio[7073]: info: Spotify config file written Feb 13 01:17:37 volumio sudo[7305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 01:17:37 volumio sudo[7305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:37 volumio volumio[7073]: 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 13 01:17:37 volumio volumio[7073]: info: Volumio called home Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 01:17:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:38 volumio go-librespot[7309]: go-librespot daemon starting... Feb 13 01:17:38 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 01:17:38 volumio systemd[1]: go-librespot-daemon.service: Killing process 7318 (go-librespot) with signal SIGKILL. Feb 13 01:17:38 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 01:17:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:38 volumio go-librespot[7324]: go-librespot daemon starting... Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:38 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:17:38 volumio sudo[7305]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:38 volumio volumio[7073]: info: No need to fix Spotify hosts Feb 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17:38+07:00" level=debug msg="app state loaded" Feb 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17: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 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17: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 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17: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 13 01:17:38 volumio go-librespot[7325]: time="2026-02-13T01:17:38+07:00" level=info msg="zeroconf server listening on port 43825" Feb 13 01:17:39 volumio go-librespot[7325]: time="2026-02-13T01:17:39+07:00" level=debug msg="obtained new client token: AAAn9DZh2KOCbfvEicnNZggR7ZlCOKnnUzXLOaaf+l52OMaW10Afuv7uCH4MOojkSr+VZx544RjS5OI4pemG5qu/ofP3PSzsw75Q2nsB7ZkOaWxNTYV2EHyO7EaWvl93uSyf4MP54lk4zt7mmJj8W4WvUtl6TXHhgt4+TEuE5G3T5rNSGgzkKlYy94ShNe37SSUtATpPBeaiVkDK+mmnsoiZal5/mCbYoX23QTq8EQYtBkew/fV7GK0=" Feb 13 01:17:39 volumio go-librespot[7325]: time="2026-02-13T01:17:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:39 volumio go-librespot[7325]: time="2026-02-13T01:17:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:17:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:39 volumio volumio[7073]: info: Starting Shairport Sync Feb 13 01:17:39 volumio volumio[7073]: info: Starting Shairport Sync Feb 13 01:17:39 volumio volumio[7073]: info: Starting Shairport Sync Feb 13 01:17:39 volumio sudo[7351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:17:39 volumio sudo[7351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:39 volumio sudo[7353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:17:39 volumio sudo[7353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:39 volumio sudo[7355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:17:39 volumio sudo[7355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:39 volumio volumio[7073]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 01:17:39 volumio volumio[7073]: SPOTIFY: BQBQ1uhqJGplC6w4x1RPOHWRRLezBBboOmL7B0aRFwg0zHD6JgZxM2JnBfpoXPaNxpB72tDvl-B_6IZCt4dFSP-peb0GQnszsXthdHEdSuGDruVpxAzKmAcb2394zh80_djownfOTZyd9PmBed3lMyPxKjqH9T6hZDfQ-N7MA-yxo5TEYJ3Bz9sGVVs3nh-au6Dwch6wYjOf_nlJGrRYgPZyL-wK1ahyLkeYBxz8A_ezU5WOveXJ0IrLOcqpObMK4E5JZBB8Er-tKh4vEs65x_WAw0w2JP0XdLTz8GqoiXx3oG0DesB_aCcH Feb 13 01:17:39 volumio volumio[7073]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 01:17:39 volumio volumio[7073]: info: New Spotify access token = BQBQ1uhqJGplC6w4x1RPOHWRRLezBBboOmL7B0aRFwg0zHD6JgZxM2JnBfpoXPaNxpB72tDvl-B_6IZCt4dFSP-peb0GQnszsXthdHEdSuGDruVpxAzKmAcb2394zh80_djownfOTZyd9PmBed3lMyPxKjqH9T6hZDfQ-N7MA-yxo5TEYJ3Bz9sGVVs3nh-au6Dwch6wYjOf_nlJGrRYgPZyL-wK1ahyLkeYBxz8A_ezU5WOveXJ0IrLOcqpObMK4E5JZBB8Er-tKh4vEs65x_WAw0w2JP0XdLTz8GqoiXx3oG0DesB_aCcH Feb 13 01:17:39 volumio volumio[7073]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 01:17:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 01:17:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 01:17:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:17:39 volumio systemd[1]: shairport-sync.service: Consumed 2.337s CPU time. Feb 13 01:17:39 volumio volumio[7073]: info: CoreCommandRouter::volumioGetState Feb 13 01:17:39 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:17:39 volumio sudo[7355]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:39 volumio sudo[7353]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:39 volumio sudo[7351]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:40 volumio volumio[7073]: info: Shairport-Sync Started Feb 13 01:17:40 volumio volumio[7073]: Error adding Membership: Error: addMembership EINVAL Feb 13 01:17:40 volumio volumio[7073]: info: Shairport-Sync Started Feb 13 01:17:40 volumio volumio[7073]: info: Shairport-Sync Started Feb 13 01:17:40 volumio volumio[7073]: 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 13 01:17:40 volumio volumio[7073]: info: Spotify Successfully logged in Feb 13 01:17:40 volumio volumio[7073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:17:40 volumio volumio[7073]: info: [1770920260364] CoreMusicLibrary::Adding element Spotify Feb 13 01:17:40 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:17:40 volumio volumio[7073]: Cannot find translation for source YouTube Music Feb 13 01:17:40 volumio volumio[7073]: Cannot find translation for source Spotify Feb 13 01:17:40 volumio volumio[7073]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 01:17:40 volumio volumio[7073]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:17:41 volumio volumio[7073]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:17:41 volumio volumio[7073]: info: CoreCommandRouter::volumioGetState Feb 13 01:17:41 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:41 volumio volumio[7073]: info: CoreStateMachine::pushState Feb 13 01:17:41 volumio volumio[7073]: info: CorePlayQueue::getTrack 0 Feb 13 01:17:41 volumio volumio[7073]: info: CoreCommandRouter::volumioPushState Feb 13 01:17:42 volumio volumio[7073]: info: go-librespot daemon successfully initialized Feb 13 01:17:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 01:17:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:42 volumio go-librespot[7391]: go-librespot daemon starting... Feb 13 01:17:42 volumio go-librespot[7392]: time="2026-02-13T01:17:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:42 volumio go-librespot[7392]: time="2026-02-13T01:17:42+07:00" level=debug msg="app state loaded" Feb 13 01:17:42 volumio go-librespot[7392]: time="2026-02-13T01:17:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:42 volumio mpd[7297]: 2026-02-13T01:17:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 01:17:42 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 01:17:42 volumio sudo[7268]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:42 volumio sudo[7259]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:42 volumio volumio[7073]: error: MPD error: The expression evaluated to a falsy value: Feb 13 01:17:42 volumio volumio[7073]: assert.ok(self.idling) Feb 13 01:17:42 volumio volumio[7073]: error: The expression evaluated to a falsy value: Feb 13 01:17:42 volumio volumio[7073]: assert.ok(self.idling) Feb 13 01:17:42 volumio volumio[7073]: error: updateQueue error: null Feb 13 01:17:43 volumio volumio[7073]: info: MPD running with PID7297 Feb 13 01:17:43 volumio volumio[7073]: ,establishing connection Feb 13 01:17:43 volumio volumio[7073]: info: Completed starting Core Plugins Feb 13 01:17:43 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:43 volumio volumio[7073]: info: ----- MyVolumio plugins startup ---- Feb 13 01:17:43 volumio volumio[7073]: info: ------------------------------------------- Feb 13 01:17:43 volumio volumio[7073]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 01:17:43 volumio volumio[7073]: error: updateQueue error: null Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+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 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+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 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+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 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+07:00" level=info msg="zeroconf server listening on port 38157" Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+07:00" level=debug msg="obtained new client token: AAA3WBbv04zF6bqzkcprklYH3tEvV5G1tHkSA25AInfSm58OCCPjtigtMF3B/C2O9atuU+rbCVP/p6nUwUsri3zEAPkZJT58C0o18No/Df2tt9zQCr3w7wN3ebawXlSLtD8Af/+cnu0mtScEwlP7p0a5943t39gyrWdmjvxYiGa45iuvsvn1xDgBbteyZhCbufHfKMJyugaYD+hT+7WJsx8N6/acDr6qNOLqXHb2GL+Riy9YmDu6jhyfqg==" Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+07:00" level=debug msg="completed challenge" Feb 13 01:17:43 volumio go-librespot[7392]: time="2026-02-13T01:17:43+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 13 01:17:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:45 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:45 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 01:17:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:47 volumio go-librespot[7404]: go-librespot daemon starting... Feb 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+07:00" level=debug msg="app state loaded" Feb 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+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 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+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 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+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 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+07:00" level=info msg="zeroconf server listening on port 34247" Feb 13 01:17:47 volumio volumio[7073]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 01:17:47 volumio go-librespot[7405]: time="2026-02-13T01:17:47+07:00" level=debug msg="obtained new client token: AAA/EZp0lOu+87K9y9MEDqLQJevB+avntHRrdsqbiGOU1pzAHpnN9+KIe58L0fLfQIKu6ekia031N9oWKk5Gdj64kxxysuOi+SburPNVYlEEahgbQk2wnBOyglcq4a/56drTJJTS8of7leLWlzkNsU1IPfGu2ut0ypnOEhqHWyww7HkgA2A//2Z3cBZ5aDWSfUEC2JC8SZvBf/M4CH2Pt4QyGegh9zTcr+YQDrXZxvr43NfT6l5R5rPmqw==" Feb 13 01:17:48 volumio go-librespot[7405]: time="2026-02-13T01:17:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:48 volumio go-librespot[7405]: time="2026-02-13T01:17:48+07:00" level=debug msg="completed keyexchange" Feb 13 01:17:48 volumio go-librespot[7405]: time="2026-02-13T01:17:48+07:00" level=debug msg="completed challenge" Feb 13 01:17:48 volumio go-librespot[7405]: time="2026-02-13T01:17:48+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 13 01:17:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:48 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:48 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:51 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:51 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 01:17:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:51 volumio go-librespot[7413]: go-librespot daemon starting... Feb 13 01:17:51 volumio go-librespot[7414]: time="2026-02-13T01:17:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:51 volumio go-librespot[7414]: time="2026-02-13T01:17:51+07:00" level=debug msg="app state loaded" Feb 13 01:17:51 volumio go-librespot[7414]: time="2026-02-13T01:17:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 01:17:51 volumio volumio[7073]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+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 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+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 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+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 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+07:00" level=info msg="zeroconf server listening on port 35001" Feb 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+07:00" level=debug msg="obtained new client token: AADFL8QQ/3FGTLbvcrhbfFdPBi5uEy6jWg2kOyWkbgwGjyTNX77b6uxWh0hZ+mk3BnwjTWFQnfQjwOhqPr24Qf8b6cOTXuNkkj/xp2ETEN7IOFv9TOEcduXHfde185n4OeksomTofbK7ZuTR/yRKsW3yaUmbcEd+KwtFyrKk+PqHMiJQy1AYDveE6FXOVugStKmJLNc0tsDpkrsDvMWaBLFYVwyslC2P7VkN2oaYgMH2j9dxGqoLl3atuA==" Feb 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:52 volumio go-librespot[7414]: time="2026-02-13T01:17:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:17:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:53 volumio volumio[7073]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 01:17:53 volumio volumio[7073]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 01:17:53 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:53 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:53 volumio volumio[7073]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 01:17:53 volumio volumio[7073]: info: MyVolumio login type: Token Feb 13 01:17:53 volumio volumio[7073]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 01:17:53 volumio volumio[7073]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 01:17:55 volumio volumio[7073]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 01:17:55 volumio volumio[7073]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 01:17:55 volumio volumio[7073]: info: Streaming services startup Feb 13 01:17:55 volumio volumio[7073]: info: Starting Streaming Daemon Feb 13 01:17:55 volumio sudo[7436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 01:17:55 volumio sudo[7436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:55 volumio volumio[7073]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 01:17:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 01:17:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:55 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:17:55 volumio go-librespot[7442]: go-librespot daemon starting... Feb 13 01:17:55 volumio sudo[7436]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:55 volumio go-librespot[7443]: time="2026-02-13T01:17:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:17:55 volumio go-librespot[7443]: time="2026-02-13T01:17:55+07:00" level=debug msg="app state loaded" Feb 13 01:17:55 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:55 volumio go-librespot[7443]: time="2026-02-13T01:17:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:17:55 volumio volumio[7073]: error: Cannot start Volumio Streaming Daemon Feb 13 01:17:55 volumio volumio[7073]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 01:17:55 volumio volumio[7073]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+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 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+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 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+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 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+07:00" level=info msg="zeroconf server listening on port 40991" Feb 13 01:17:56 volumio volumio[7073]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+07:00" level=debug msg="obtained new client token: AABoOILT+j2ZSq8BT/gBdjdCYo5xDFtWTF2unmCukYeUZlIxXFQ7u2rMY6K6GT2QM991f9jk/tP4pTAxWwNOzR0GGvy4KSyvy28yM9pK8MMkVeVp10PClGjY4rygqFePUiAZCNyyiHVI8d4+R0hhmTJZmQ+xR7sP+bBAcr+0HTOhduAK9zhV1E3zN1jpMFjWjfcgJBfVD1MTZixSuEbQPquwkQArteUXq18qY78U1LyzrRHNwllWYEEa9Q==" Feb 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:17:56 volumio go-librespot[7443]: time="2026-02-13T01:17:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:17:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:17:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:17:57 volumio volumio[7073]: info: MyVolumio token set successfully Feb 13 01:17:57 volumio volumio[7073]: info: MYVOLUMIO: Adding device Feb 13 01:17:57 volumio volumio[7073]: info: MYVOLUMIO: Evaluating Server Feb 13 01:17:58 volumio volumio[7073]: info: MyVolumio status changed Feb 13 01:17:58 volumio volumio[7073]: info: Streaming services startup Feb 13 01:17:58 volumio volumio[7073]: info: Starting Streaming Daemon Feb 13 01:17:58 volumio volumio[7073]: info: Removing browser output: myVolumio user plan is not superstar Feb 13 01:17:58 volumio volumio[7073]: info: Removing audio output: Feb 13 01:17:58 volumio volumio[7073]: info: Stoppping Tunnel 1 Feb 13 01:17:58 volumio sudo[7472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 01:17:58 volumio sudo[7472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:58 volumio sudo[7472]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:58 volumio sudo[7475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 13 01:17:58 volumio sudo[7475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:17:58 volumio volumio[7073]: error: Cannot start Volumio Streaming Daemon Feb 13 01:17:58 volumio volumio[7073]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 01:17:58 volumio volumio[7073]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 01:17:58 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 13 01:17:58 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 13 01:17:58 volumio volumio[7073]: info: Setting Geolocation for MyVolumio to as1 Feb 13 01:17:58 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:58 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:58 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:58 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 13 01:17:58 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 13 01:17:58 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 13 01:17:58 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 13 01:17:58 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 13 01:17:58 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 13 01:17:58 volumio sudo[7475]: pam_unix(sudo:session): session closed for user root Feb 13 01:17:58 volumio volumio[7073]: info: Remote SSH Stopped Feb 13 01:17:58 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:17:58 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:17:58 volumio volumio[7073]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 13 01:17:59 volumio volumio[7073]: info: Updating MyVolumio device info Feb 13 01:17:59 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:59 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:59 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:17:59 volumio volumio[7073]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 13 01:18:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 01:18:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:00 volumio go-librespot[7477]: go-librespot daemon starting... Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=debug msg="app state loaded" Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+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 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+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 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+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 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=info msg="zeroconf server listening on port 46805" Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=debug msg="obtained new client token: AADdXitaqVw8WtcdZ3xiPy0xD0L7vwmdpQUBWttLfBB+/5zwb53FmFmGngLdh5pYNsOiCEMxoXJwMNVKfqkL0hH/pPb8yxGGhjF5Xy2e4O5fGfswRH9RWaHwvpUR/ETmjj58aHPQO2eN4hfRDOC52sn1wfb0vJAbmMaSWEhbrsEipdDfp1qra5DC6jRD892nL9K8pCNBSnNP1tbbAsqEC6zOBSApQJyVPyG2MSgQAuGv9vJ0pT0NfpDC/Q==" Feb 13 01:18:00 volumio go-librespot[7478]: time="2026-02-13T01:18:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:01 volumio go-librespot[7478]: time="2026-02-13T01:18:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:01 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:18:01 volumio volumio[7073]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:18:02 volumio volumio[7073]: info: MYVOLUMIO: Adding device Feb 13 01:18:02 volumio volumio[7073]: info: MYVOLUMIO: Evaluating Server Feb 13 01:18:03 volumio volumio[7073]: info: Setting Geolocation for MyVolumio to as1 Feb 13 01:18:03 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:03 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:03 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 01:18:04 volumio volumio[7073]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 13 01:18:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:04 volumio go-librespot[7518]: go-librespot daemon starting... Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=debug msg="app state loaded" Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:04 volumio volumio[7073]: info: Updating MyVolumio device info Feb 13 01:18:04 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:04 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:04 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+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 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+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 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+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 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=info msg="zeroconf server listening on port 39145" Feb 13 01:18:04 volumio volumio[7073]: info: Initializing connection to go-librespot Websocket Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=debug msg="new websocket client" Feb 13 01:18:04 volumio volumio[7073]: info: Connection to go-librespot Websocket established Feb 13 01:18:04 volumio go-librespot[7519]: time="2026-02-13T01:18:04+07:00" level=debug msg="obtained new client token: AADN6daKdVNm3jFG4BXjQEecawoMYiJAeGOs8vzvVGnrl6/fculgJ5u98pvD8jw5GM5b10CoCc1juEyTCoe/0ZTKP3RdT5NV/KJdJCdHyOfB5IHT1tAhuvb6RPkdOzum8cxB71eYwvB8KnoWHZJL2vhEvFPzuq/CsXr3o6tt2YR6pbW/NwX5uI3FaNBCfbg8j4kJX9AGphniGLZ3v12ODVn7lszWwE8M5q15sQ7ePQSPcdMG14qZZFmWvw==" Feb 13 01:18:05 volumio volumio[7073]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 13 01:18:05 volumio go-librespot[7519]: time="2026-02-13T01:18:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:05 volumio go-librespot[7519]: time="2026-02-13T01:18:05+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:05 volumio go-librespot[7519]: time="2026-02-13T01:18:05+07:00" level=debug msg="completed challenge" Feb 13 01:18:05 volumio go-librespot[7519]: time="2026-02-13T01:18:05+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 13 01:18:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:05 volumio volumio[7073]: info: Connection to go-librespot Websocket closed Feb 13 01:18:06 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:18:06 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:06 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 13 01:18:06 volumio volumio-remote-updater[643]: Test mode disabled Feb 13 01:18:06 volumio volumio-remote-updater[643]: Alpha mode disabled Feb 13 01:18:06 volumio volumio-remote-updater[643]: Alpha legacy test mode disabled Feb 13 01:18:06 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 13 01:18:07 volumio volumio[7073]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

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

NEW ADDITIONS

\n
    \n
  • Add BLE onboarding plugin
    • \n
  • Enable vc4-kms-v3d for all compatible RPi models
    • \n
  • Update Tidal SDK
    • \n
\n","title":"Update v4.096","updateavailable":true} Feb 13 01:18:07 volumio volumio[7073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 13 01:18:07 volumio volumio[7073]: info: Getting Spotify volume Feb 13 01:18:07 volumio volumio[7073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:18:07 volumio volumio[7073]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:18:07 volumio volumio[7073]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 01:18:07 volumio volumio[7073]: errno: -111, Feb 13 01:18:07 volumio volumio[7073]: code: 'ECONNREFUSED', Feb 13 01:18:07 volumio volumio[7073]: syscall: 'connect', Feb 13 01:18:07 volumio volumio[7073]: address: '127.0.0.1', Feb 13 01:18:07 volumio volumio[7073]: port: 9879, Feb 13 01:18:07 volumio volumio[7073]: response: undefined Feb 13 01:18:07 volumio volumio[7073]: } Feb 13 01:18:07 volumio volumio[7073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:18:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 01:18:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:08 volumio go-librespot[7542]: go-librespot daemon starting... Feb 13 01:18:08 volumio go-librespot[7543]: time="2026-02-13T01:18:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:08 volumio go-librespot[7543]: time="2026-02-13T01:18:08+07:00" level=debug msg="app state loaded" Feb 13 01:18:08 volumio go-librespot[7543]: time="2026-02-13T01:18:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+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 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+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 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+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 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+07:00" level=info msg="zeroconf server listening on port 35679" Feb 13 01:18:09 volumio sudo[7552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 01:17' Feb 13 01:18:09 volumio sudo[7552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+07:00" level=debug msg="obtained new client token: AACgohfhn5gFGjQdqwPNmDV45WiLkYMWMrwCazJY35w9io0hBXCYfo7sTE5lElc4yb47SPzHwyK2YKcTSMWo8SoCRsA2A1b/PN7XLNnlX6YAUcZODlRT7UR9/owm0bhWUOQGp1MCb9kTrXBhpWORYSVMcQEwpInlUlU4Huosrl8Vd9MfUNYUqEYbRWn0u9QcmTYoGID285K77bZFZk7S7eCFDQQkP4oubXqoEM8WxvkdzZz7ifsp3/KOLQ==" Feb 13 01:18:09 volumio sudo[7552]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+07:00" level=debug msg="completed challenge" Feb 13 01:18:09 volumio volumio-remote-updater[643]: [2026-02-13 01:18:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 01:18:09 volumio volumio-remote-updater[643]: [2026-02-13 01:18:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 01:18:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 01:18:09 volumio systemd[1]: volumio.service: Consumed 58.658s CPU time. Feb 13 01:18:09 volumio go-librespot[7543]: time="2026-02-13T01:18:09+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 13 01:18:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 01:18:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 01:18:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14036. Feb 13 01:18:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 01:18:09 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 01:18:09 volumio systemd[1]: volumio.service: Consumed 58.658s CPU time. Feb 13 01:18:09 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 01:18:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 01:18:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 01:18:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:12 volumio go-librespot[7583]: go-librespot daemon starting... Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=debug msg="app state loaded" Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:13 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:13 volumio volumio[7568]: info: ----- Volumio3 ---- Feb 13 01:18:13 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:13 volumio volumio[7568]: info: ----- System startup ---- Feb 13 01:18:13 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+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 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+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 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+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 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=info msg="zeroconf server listening on port 35479" Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=debug msg="obtained new client token: AAAVLC25VK9PFBTSR0eRfY0OIBB/iL6AxvJ0FfcIigYH55DP3VOmJOTgXNXa/Emwqj9ChMv0/kdoTQEb1trM3JG9YwjWyPLLlSWQVfBa1rTuSX1cuPKIob9bqT2TRLNYTcvMP1O9WrBzIpKatPagKk2oBMNDkALtwnG793O+DxpvYzIOhzg/B2+cDqY6oGnxS5T3Rzh2tx2sSzX/0MF+DxW0DKL1za4FvLqEOuKUV+t4BZ5j9JpIkirziw==" Feb 13 01:18:13 volumio go-librespot[7584]: time="2026-02-13T01:18:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:14 volumio go-librespot[7584]: time="2026-02-13T01:18:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:14 volumio volumio-remote-updater[643]: [2026-02-13 01:18:14] [connect] Successful connection Feb 13 01:18:14 volumio volumio[7568]: info: MYVOLUMIO Environment detected Feb 13 01:18:15 volumio volumio[7568]: info: Plugin folders cleanup Feb 13 01:18:15 volumio volumio[7568]: info: Scanning into folder /volumio/app/plugins/ Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category audio_interface Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category miscellanea Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category music_service Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category plugins.json Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category system_controller Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category user_interface Feb 13 01:18:15 volumio volumio[7568]: info: Scanning into folder /data/plugins/ Feb 13 01:18:15 volumio volumio[7568]: info: Scanning category music_service Feb 13 01:18:15 volumio volumio[7568]: info: Plugin folders cleanup completed Feb 13 01:18:15 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:15 volumio volumio[7568]: info: ----- Core plugins startup ---- Feb 13 01:18:15 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:15 volumio volumio[7568]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 01:18:15 volumio volumio[7568]: info: Adding plugin upnp to MyMusic Plugins Feb 13 01:18:15 volumio volumio[7568]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 01:18:15 volumio volumio[7568]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 01:18:15 volumio volumio[7568]: info: Loading plugins from folder /data/plugins/ Feb 13 01:18:15 volumio volumio[7568]: info: Loading plugin "system"... Feb 13 01:18:15 volumio volumio[7568]: info: Loading plugin "appearance"... Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "network"... Feb 13 01:18:17 volumio volumio[7568]: info: Refreshing Cached IP Addresses Feb 13 01:18:17 volumio sudo[7606]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 01:18:17 volumio sudo[7606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:17 volumio sudo[7608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 01:18:17 volumio sudo[7608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:17 volumio sudo[7606]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "services"... Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "alsa_controller"... Feb 13 01:18:17 volumio sudo[7608]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:17 volumio sudo[7616]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 01:18:17 volumio sudo[7616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:17 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "wizard"... Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "networkfs"... Feb 13 01:18:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 01:18:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:17 volumio volumio[7568]: info: Starting Udev Watcher for removable devices Feb 13 01:18:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:17 volumio go-librespot[7639]: go-librespot daemon starting... Feb 13 01:18:17 volumio volumio[7568]: info: Ignoring mount for partition: boot Feb 13 01:18:17 volumio volumio[7568]: info: Ignoring mount for partition: volumio Feb 13 01:18:17 volumio volumio[7568]: info: Ignoring mount for partition: volumio_data Feb 13 01:18:17 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "volumio_command_line_client"... Feb 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+07:00" level=debug msg="app state loaded" Feb 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "upnp"... Feb 13 01:18:17 volumio volumio[7568]: info: [1770920297450] Starting Upmpd Daemon Feb 13 01:18:17 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "my_music"... Feb 13 01:18:17 volumio volumio[7568]: info: Loading plugin "mpd"... Feb 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+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 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+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 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+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 13 01:18:17 volumio go-librespot[7640]: time="2026-02-13T01:18:17+07:00" level=info msg="zeroconf server listening on port 37555" Feb 13 01:18:18 volumio volumio[7568]: info: Loading plugin "upnp_browser"... Feb 13 01:18:18 volumio go-librespot[7640]: time="2026-02-13T01:18:18+07:00" level=debug msg="obtained new client token: AABwCoZxcA98WhGcSYA7lCEjMV9t4sGMDOlSW70qQIUIg03BB6kWXYR9/m0u2NZ/+q8+ZrIdVJ41/SMj7+77jlYloU02eGC2Iy9M2OLB5P1XnxI6eClrWhtYVgAOHS5cV+Lglc70HYOMv98t4xVn8Bmxrr1ih6+NVBy54mdBwc0Pr1t+A/xllrxjqdKyjDgjTshx1VpBAA5Yoxwt0CcEPswU62kyQpfIQXRxXy8u/OyBwEmD/AoBNFo=" Feb 13 01:18:18 volumio go-librespot[7640]: time="2026-02-13T01:18:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:18 volumio go-librespot[7640]: time="2026-02-13T01:18:18+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:18 volumio go-librespot[7640]: time="2026-02-13T01:18:18+07:00" level=debug msg="completed challenge" Feb 13 01:18:18 volumio go-librespot[7640]: time="2026-02-13T01:18:18+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 13 01:18:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:20 volumio sudo[7616]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:21 volumio volumio[7568]: info: Starting UPNP Browser Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "alarm-clock"... Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "airplay_emulation"... Feb 13 01:18:21 volumio volumio[7568]: info: Starting Shairport Sync Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "last_100"... Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "webradio"... Feb 13 01:18:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 01:18:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "i2s_dacs"... Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "volumiodiscovery"... Feb 13 01:18:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:21 volumio go-librespot[7647]: go-librespot daemon starting... Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** For more information see Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:18:21 volumio volumio[7568]: *** WARNING *** For more information see Feb 13 01:18:21 volumio node[7568]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 01:18:21 volumio node[7568]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:18:21 volumio node[7568]: *** WARNING *** For more information see Feb 13 01:18:21 volumio node[7568]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 01:18:21 volumio node[7568]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 01:18:21 volumio node[7568]: *** WARNING *** For more information see Feb 13 01:18:21 volumio volumio[7568]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 01:18:21 volumio go-librespot[7648]: time="2026-02-13T01:18:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:21 volumio go-librespot[7648]: time="2026-02-13T01:18:21+07:00" level=debug msg="app state loaded" Feb 13 01:18:21 volumio volumio[7568]: info: Discovery: Started advertising with name: Volumio Feb 13 01:18:21 volumio go-librespot[7648]: time="2026-02-13T01:18:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:21 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 01:18:21 volumio volumio[7568]: info: Loading plugin "spop"... Feb 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+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 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+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 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+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 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+07:00" level=info msg="zeroconf server listening on port 46471" Feb 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+07:00" level=debug msg="obtained new client token: AABd5FdIvOygSMupOHwGa+ajfnGX6Sum5ehadWdFwPEtDyyeoNlvVVHUVvAfy63+5keN/F1qbOAPTmZtmvbQXLT1u3HqqsIphaigKOIGR6UadrVV4qxAJfhIMEtFBDvQAVOxrwFDz/FM7Utbt3BKfXTTDLvhu3RvGg5gorIrU3S4q0zYPR1/ptfEwDuhWEyrsV2t5FXNUO/uCJe7o/f6Pim0lX80pUMMCHffnWnUpGyVyxLu29EnyXLOdw==" Feb 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:22 volumio go-librespot[7648]: time="2026-02-13T01:18:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:23 volumio volumio[7568]: info: Loading plugin "ytcr"... Feb 13 01:18:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 13 01:18:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:25 volumio go-librespot[7657]: go-librespot daemon starting... Feb 13 01:18:25 volumio go-librespot[7658]: time="2026-02-13T01:18:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:25 volumio go-librespot[7658]: time="2026-02-13T01:18:25+07:00" level=debug msg="app state loaded" Feb 13 01:18:25 volumio go-librespot[7658]: time="2026-02-13T01:18:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:26 volumio volumio[7568]: info: Loading plugin "ytmusic"... Feb 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+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 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+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 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+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 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+07:00" level=info msg="zeroconf server listening on port 42277" Feb 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+07:00" level=debug msg="obtained new client token: AADm+SPv8Ceik+wlcDGsIuu7dOhn6ccPCLd/FtX9F50DFHn3XlfILJS9RBYYC9CDe79fBKkY9H5PGafSkdyhLVzWh/wY3AnRbYK3tnD8pEyzKjtbwlySIGBgQpvoJX2CZu86xcQ9WdfO2ui7fZdwlm4YpymEtJpp7rafXcDjzq4eASAcF8E2dBdy0R3fSMKdy1cNkVadtt1JdPs5hQFSj0kXHnSJM8gN7JHoHD1iLO4seLaPjOYsp7DVew==" Feb 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:26 volumio go-librespot[7658]: time="2026-02-13T01:18:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:27 volumio volumio-remote-updater[643]: [2026-02-13 01:18:27] [connect] Successful connection Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "outputs"... Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "albumart"... Feb 13 01:18:27 volumio volumio[7568]: info: Plugin example_plugin is not enabled Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "inputs"... Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "updater_comm"... Feb 13 01:18:27 volumio volumio[7568]: info: Plugin mpdemulation is not enabled Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "rest_api"... Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "websocket"... Feb 13 01:18:27 volumio volumio[7568]: info: Starting Socket.io Server version 1.7.4 Feb 13 01:18:27 volumio volumio[7568]: info: Loading plugin "RoonBridge"... Feb 13 01:18:28 volumio volumio[7568]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 01:18:28 volumio volumio[7568]: info: Loading i18n strings for locale en Feb 13 01:18:28 volumio volumio[7568]: Updating browse sources language Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::initPlayerControls Feb 13 01:18:28 volumio volumio[7679]: Forking 3 albumart workers Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:18:28 volumio volumio[7568]: Express server listening on port 3000 Feb 13 01:18:28 volumio volumio[7568]: [Metrics] WebUI: 16s 1.38ms Feb 13 01:18:28 volumio volumio[7568]: info: CoreStateMachine::resetVolumioState Feb 13 01:18:28 volumio volumio[7568]: info: CoreStateMachine::getcurrentVolume Feb 13 01:18:28 volumio volumio[7568]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:18:28 volumio sudo[7721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 01:18:28 volumio sudo[7721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:28 volumio sudo[7721]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:28 volumio sudo[7723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 01:18:28 volumio sudo[7723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:28 volumio sudo[7723]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:28 volumio volumio[7568]: info: Volumio Network Manager: Network status updated: 1 Feb 13 01:18:29 volumio volumio[7568]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::pushState Feb 13 01:18:29 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioPushState Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::updateTrackBlock Feb 13 01:18:29 volumio volumio[7568]: info: CorePlayQueue::getTrackBlock Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:18:29 volumio volumio-remote-updater[643]: [2026-02-13 01:18:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770920307 101 Feb 13 01:18:29 volumio volumio[7568]: 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 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:29 volumio volumio[7568]: info: Reloading queue from file Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::setRepeat null single undefined Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::pushState Feb 13 01:18:29 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioPushState Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::setRandom null Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::pushState Feb 13 01:18:29 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioPushState Feb 13 01:18:29 volumio volumio[7568]: info: Setting Device type: Raspberry PI Feb 13 01:18:29 volumio volumio[7568]: info: Completed loading Core Plugins Feb 13 01:18:29 volumio volumio[7568]: info: Preparing to generate the ALSA configuration file Feb 13 01:18:29 volumio volumio[7568]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:18:29 volumio volumio[7568]: info: CoreStateMachine::pushState Feb 13 01:18:29 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioPushState Feb 13 01:18:29 volumio volumio[7568]: info: Asound.conf file unchanged, so no further update is needed Feb 13 01:18:29 volumio volumio[7568]: info: Output device has changed, restarting MPD Feb 13 01:18:29 volumio volumio[7568]: info: Output device has changed, restarting Shairport Sync Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:29 volumio sudo[7739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 01:18:29 volumio sudo[7739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:29 volumio sudo[7741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 01:18:29 volumio sudo[7741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:29 volumio sudo[7743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 01:18:29 volumio sudo[7743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:29 volumio sudo[7741]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:29 volumio volumio[7568]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:18:29 volumio volumio[7568]: info: ___________ START PLUGINS ___________ Feb 13 01:18:29 volumio volumio[7568]: info: ControllerMpd::onStart: Initializing MPD Feb 13 01:18:29 volumio volumio[7568]: info: Creating MPD Configuration file Feb 13 01:18:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 01:18:29 volumio sudo[7739]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:18:29 volumio volumio[7568]: info: [1770920309942] CoreMusicLibrary::Adding element Media Servers Feb 13 01:18:29 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 13 01:18:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:30 volumio volumio[7568]: info: UPNP Browser: Client initialized successfully Feb 13 01:18:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:30 volumio go-librespot[7755]: go-librespot daemon starting... Feb 13 01:18:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 01:18:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 01:18:30 volumio systemd[1]: mpd.service: Consumed 7.198s CPU time. Feb 13 01:18:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 01:18:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 01:18:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 01:18:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 01:18:30 volumio sudo[7754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 01:18:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+07:00" level=debug msg="app state loaded" Feb 13 01:18:30 volumio sudo[7752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 01:18:30 volumio sudo[7752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:30 volumio sudo[7754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:30 volumio sudo[7752]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:30 volumio volumio[7568]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:30 volumio volumio[7568]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 01:18:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 01:18:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 01:18:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:18:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 01:18:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 01:18:30 volumio volumio[7568]: info: [1770920310377] CoreMusicLibrary::Adding element Last_100 Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:18:30 volumio volumio[7568]: info: [1770920310386] CoreMusicLibrary::Adding element Webradio Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 01:18:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:18:30 volumio volumio[7568]: info: Initializing BBC Radios Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:30 volumio volumio[7568]: info: Creating Spotify config file Feb 13 01:18:30 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+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 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+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 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+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 13 01:18:30 volumio go-librespot[7756]: time="2026-02-13T01:18:30+07:00" level=info msg="zeroconf server listening on port 42967" Feb 13 01:18:30 volumio sudo[7776]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 01:18:30 volumio sudo[7776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 01:18:30 volumio sudo[7776]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:31 volumio go-librespot[7756]: time="2026-02-13T01:18:31+07:00" level=debug msg="obtained new client token: AAAMSGS0g1ZkhIwA37EJm8ADZ3x/2Az32hGl1mILRjLKAhB81hzObDL68zOqhQkRzH0T9x+O60z7NhvxlhS2ZLSCJ4/xXKu6OaMHjNLYmVbRe9QGM3gEjgWSxvBQQPl9olgdhlrtAZ06Ajoryu+yOCuYiyMqCJdIxZRCaqz5hVPI9BpuOATqLAqLJMNyb5kliLElGF+/Ho64QBiDzrLvQcvHA5UgM9XA1qv5qtUfMsPd1NteMBwCzjw=" Feb 13 01:18:31 volumio go-librespot[7756]: time="2026-02-13T01:18:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:31 volumio go-librespot[7756]: time="2026-02-13T01:18:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:31 volumio volumio[7689]: Starting albumart workers Feb 13 01:18:31 volumio volumio[7692]: Starting albumart workers Feb 13 01:18:32 volumio volumio[7568]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:18:32 volumio volumio[7568]: info: [1770920312216] CoreMusicLibrary::Adding element YouTube Music Feb 13 01:18:32 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:32 volumio volumio[7568]: Cannot find translation for source YouTube Music Feb 13 01:18:32 volumio volumio[7568]: info: Volumio Calling Home Feb 13 01:18:32 volumio sudo[7793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 01:18:32 volumio sudo[7793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:32 volumio sudo[7793]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:32 volumio volumio[7690]: Starting albumart workers Feb 13 01:18:32 volumio volumio[7568]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 01:18:32 volumio volumio[7568]: info: Discovery: Found device Volumio Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:33 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:33 volumio volumio[7568]: info: MPD Permissions set Feb 13 01:18:33 volumio volumio[7568]: info: MPD Permissions set Feb 13 01:18:33 volumio volumio[7568]: info: Upmpdcli Daemon Started Feb 13 01:18:33 volumio volumio[7568]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 01:18:33 volumio volumio[7568]: info: Discovery: Found device Volumio Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:33 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:33 volumio volumio[7568]: info: Spotify config file written Feb 13 01:18:33 volumio volumio[7568]: 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 13 01:18:33 volumio sudo[7800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 01:18:33 volumio sudo[7800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:33 volumio go-librespot[7807]: go-librespot daemon starting... Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:33 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 01:18:33 volumio sudo[7800]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:33 volumio volumio[7568]: info: No need to fix Spotify hosts Feb 13 01:18:33 volumio go-librespot[7813]: time="2026-02-13T01:18:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:33 volumio go-librespot[7813]: time="2026-02-13T01:18:33+07:00" level=debug msg="app state loaded" Feb 13 01:18:33 volumio go-librespot[7813]: time="2026-02-13T01:18:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+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 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+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 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+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 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+07:00" level=info msg="zeroconf server listening on port 43441" Feb 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+07:00" level=debug msg="obtained new client token: AAC0kFLFopIr2cXBHRtmmxs5yud/OS4u+8OUHPW1NCcr/J+3p5iCryz6qWQyuUt01kiRvMHgHTMHb82GQLUJrx7vytIASlg+2Jp2ADsoVJ8eXQEBkhZJm3hk+o7U4OwH/JVecUKV+tZ/RJcLW4DVxL8HiuX144VUlOOTeU7x/FzkXiURO7Ilt8zZ+QulsDqJAie6StFgjKMqZ36Vf5ieFZsEmNlK0adNfXhhj18kCgFMgzlUrjYK5SYOrg==" Feb 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:34 volumio volumio[7568]: info: Volumio called home Feb 13 01:18:34 volumio go-librespot[7813]: time="2026-02-13T01:18:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:34 volumio volumio[7568]: info: Starting Shairport Sync Feb 13 01:18:34 volumio volumio[7568]: info: Starting Shairport Sync Feb 13 01:18:34 volumio volumio[7568]: info: Starting Shairport Sync Feb 13 01:18:34 volumio sudo[7842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:18:34 volumio sudo[7842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:34 volumio sudo[7840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:18:34 volumio sudo[7840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:34 volumio sudo[7844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 01:18:34 volumio sudo[7844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 01:18:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 01:18:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:18:35 volumio systemd[1]: shairport-sync.service: Consumed 2.467s CPU time. Feb 13 01:18:35 volumio volumio[7568]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 01:18:35 volumio volumio[7568]: SPOTIFY: BQADbJ6IkxKhmSGgg-tnIH4STcuVo975ZuUeGezOTvW65ykFS2y3ZBD5_MFLqhKZ1nTHbxDq-atZvtSsCYx8ieSBBAUQ9OZ10E93sUMo0ULFoNtJuUf-7jpa-JlXDDgRBeGGHCaaVg0t5hPtcx1g8kKT7wkZoaDBmo1ZaHwHCR7uTds8ypcr6p1ZjW0Hhz8W8z5iR-ipSPQQvRkWxiOOdu9LzSaMaX8FneTZtW-g4mF5MXQfLtn7uOzphN1LtDnvxl5ibPf-Ggf2SrWdgiVshfeWarxzZQB1DFit8CvV7zvAw1Nbx9h-X7pk Feb 13 01:18:35 volumio volumio[7568]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 01:18:35 volumio volumio[7568]: info: New Spotify access token = BQADbJ6IkxKhmSGgg-tnIH4STcuVo975ZuUeGezOTvW65ykFS2y3ZBD5_MFLqhKZ1nTHbxDq-atZvtSsCYx8ieSBBAUQ9OZ10E93sUMo0ULFoNtJuUf-7jpa-JlXDDgRBeGGHCaaVg0t5hPtcx1g8kKT7wkZoaDBmo1ZaHwHCR7uTds8ypcr6p1ZjW0Hhz8W8z5iR-ipSPQQvRkWxiOOdu9LzSaMaX8FneTZtW-g4mF5MXQfLtn7uOzphN1LtDnvxl5ibPf-Ggf2SrWdgiVshfeWarxzZQB1DFit8CvV7zvAw1Nbx9h-X7pk Feb 13 01:18:35 volumio volumio[7568]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 01:18:35 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:35 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:18:35 volumio sudo[7842]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:35 volumio sudo[7840]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:35 volumio volumio[7568]: info: Shairport-Sync Started Feb 13 01:18:35 volumio volumio[7568]: Error adding Membership: Error: addMembership EINVAL Feb 13 01:18:35 volumio volumio[7568]: info: Shairport-Sync Started Feb 13 01:18:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 01:18:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 01:18:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:18:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 01:18:35 volumio sudo[7844]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:35 volumio volumio[7568]: info: Shairport-Sync Started Feb 13 01:18:35 volumio volumio[7568]: 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 13 01:18:35 volumio volumio[7568]: info: Spotify Successfully logged in Feb 13 01:18:35 volumio volumio[7568]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 01:18:35 volumio volumio[7568]: info: [1770920315585] CoreMusicLibrary::Adding element Spotify Feb 13 01:18:35 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 01:18:35 volumio volumio[7568]: Cannot find translation for source YouTube Music Feb 13 01:18:35 volumio volumio[7568]: Cannot find translation for source Spotify Feb 13 01:18:36 volumio volumio[7568]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 01:18:36 volumio volumio[7568]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 01:18:36 volumio nmbd[966]: [2026/02/13 01:18:36.447970, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 13 01:18:36 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 13 01:18:36 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 13 01:18:36 volumio volumio[7568]: info: VolumeController:: Volume=100 Mute =false Feb 13 01:18:36 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:36 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:36 volumio volumio[7568]: info: CoreStateMachine::pushState Feb 13 01:18:36 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:36 volumio volumio[7568]: info: CoreCommandRouter::volumioPushState Feb 13 01:18:37 volumio volumio[7568]: info: go-librespot daemon successfully initialized Feb 13 01:18:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 13 01:18:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:37 volumio go-librespot[7882]: go-librespot daemon starting... Feb 13 01:18:37 volumio go-librespot[7883]: time="2026-02-13T01:18:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:37 volumio go-librespot[7883]: time="2026-02-13T01:18:37+07:00" level=debug msg="app state loaded" Feb 13 01:18:37 volumio go-librespot[7883]: time="2026-02-13T01:18:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:38 volumio mpd[7791]: 2026-02-13T01:18:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 01:18:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 01:18:38 volumio sudo[7754]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:38 volumio sudo[7743]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:38 volumio volumio[7568]: error: MPD error: The expression evaluated to a falsy value: Feb 13 01:18:38 volumio volumio[7568]: assert.ok(self.idling) Feb 13 01:18:38 volumio volumio[7568]: error: The expression evaluated to a falsy value: Feb 13 01:18:38 volumio volumio[7568]: assert.ok(self.idling) Feb 13 01:18:38 volumio volumio[7568]: error: updateQueue error: null Feb 13 01:18:38 volumio volumio[7568]: info: MPD running with PID7791 Feb 13 01:18:38 volumio volumio[7568]: ,establishing connection Feb 13 01:18:38 volumio volumio[7568]: info: Completed starting Core Plugins Feb 13 01:18:38 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:38 volumio volumio[7568]: info: ----- MyVolumio plugins startup ---- Feb 13 01:18:38 volumio volumio[7568]: info: ------------------------------------------- Feb 13 01:18:38 volumio volumio[7568]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+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 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+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 13 01:18:38 volumio volumio[7568]: error: updateQueue error: null Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+07:00" level=info msg="zeroconf server listening on port 34403" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+07:00" level=debug msg="obtained new client token: AABCvd94+O9kHNTwHJBDYiFEbiNepfGDXCd9KFBfYHIMJOCkO7p4IU9mS29GNJSIjukgol3FQjswCjlKClh4n/t2ER8MZDWWbshU+LItY3QeWD6IO2CFH5RyAd1DhzJFu7MYxjJMAFYIZifvTo4PbST+kcuz8HXxC7k6ZbjM3cECCNQataAo9UhErNwDb+UdaEs9VVqL9YItgWfdQ3l0c3poD718df0IV3J82msAS4rZUIEPJ2cj8qC0Hg==" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18:38+07:00" level=debug msg="completed challenge" Feb 13 01:18:38 volumio go-librespot[7883]: time="2026-02-13T01:18: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 13 01:18:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:40 volumio volumio[7568]: info: Initializing connection to go-librespot Websocket Feb 13 01:18:40 volumio volumio[7568]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:18:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 13 01:18:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:42 volumio go-librespot[7894]: go-librespot daemon starting... Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=debug msg="app state loaded" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+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 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+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 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=info msg="zeroconf server listening on port 40045" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=debug msg="obtained new client token: AADvSKutURDb8jPEFqcsWjg50QqT1h4EI0lfOvrEdU8HSip826PqsgPcD9If6IJlMIC+AQaGLXRBo+yr/b1gv8wIe0PFtLuCw41VTtuaHmXnWSoy9xuw/0lcITdaeBVY2gFb6MNmrf8XYp6Q4xAdctfgy6m9p2Ak3QPf3ocw/BFx8asa4k4ORMjFSmSetheQE6cNuMwP/4yYEPvFUS4+zEdNqy5g7qg38yBM+YRRGEJDGCWda20jHpgWXw==" Feb 13 01:18:42 volumio go-librespot[7895]: time="2026-02-13T01:18:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:43 volumio go-librespot[7895]: time="2026-02-13T01:18:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 13 01:18:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:43 volumio volumio[7568]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 01:18:43 volumio volumio[7568]: info: Initializing connection to go-librespot Websocket Feb 13 01:18:43 volumio volumio[7568]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 01:18:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 13 01:18:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:46 volumio go-librespot[7903]: go-librespot daemon starting... Feb 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18:46+07:00" level=debug msg="app state loaded" Feb 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:46 volumio volumio[7568]: info: Initializing connection to go-librespot Websocket Feb 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18:46+07:00" level=debug msg="new websocket client" Feb 13 01:18:46 volumio volumio[7568]: info: Connection to go-librespot Websocket established Feb 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18: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 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18: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 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18: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 13 01:18:46 volumio go-librespot[7904]: time="2026-02-13T01:18:46+07:00" level=info msg="zeroconf server listening on port 33411" Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 01:18:47 volumio go-librespot[7904]: time="2026-02-13T01:18:47+07:00" level=debug msg="obtained new client token: AABqHRBMpLE2ASFtE29WksJyYdPcGOY9eEe5i3zw/lKV/8QA0ueSipGuAWiuQWxT1f0nP9rn9x4hhRyvzMqQa0QgeMKwg/+VCzivawyVFUn79/upJd9N9G86ZcmGM7EUkfoarPeF1vS42sD9IORcvfgU0pgDENCS/pz15B4tKN73beWH8jZt8JKXzdjlFRlHNcZqw3KYWLBCtkhil6jF0lJuWUjo5qgixnON9Fi61yUOaBh4ALAgLHI=" Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 01:18:47 volumio volumio[7568]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 01:18:47 volumio go-librespot[7904]: time="2026-02-13T01:18:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:47 volumio go-librespot[7904]: time="2026-02-13T01:18:47+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:47 volumio go-librespot[7904]: time="2026-02-13T01:18:47+07:00" level=debug msg="completed challenge" Feb 13 01:18:47 volumio go-librespot[7904]: time="2026-02-13T01:18: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 13 01:18:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:49 volumio volumio[7568]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 01:18:49 volumio volumio[7568]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 01:18:49 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:49 volumio volumio[7568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 01:18:49 volumio volumio[7568]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 01:18:49 volumio volumio[7568]: info: MyVolumio login type: Token Feb 13 01:18:49 volumio volumio[7568]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 01:18:49 volumio volumio[7568]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 01:18:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 13 01:18:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 01:18:50 volumio go-librespot[7925]: go-librespot daemon starting... Feb 13 01:18:50 volumio go-librespot[7926]: time="2026-02-13T01:18:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 01:18:50 volumio go-librespot[7926]: time="2026-02-13T01:18:50+07:00" level=debug msg="app state loaded" Feb 13 01:18:50 volumio go-librespot[7926]: time="2026-02-13T01:18:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 01:18:50 volumio volumio[7568]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 01:18:50 volumio volumio[7568]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 01:18:50 volumio volumio[7568]: info: Streaming services startup Feb 13 01:18:50 volumio volumio[7568]: info: Starting Streaming Daemon Feb 13 01:18:50 volumio sudo[7933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 01:18:50 volumio sudo[7933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 01:18:50 volumio volumio[7568]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 01:18:51 volumio sudo[7933]: pam_unix(sudo:session): session closed for user root Feb 13 01:18:51 volumio volumio[7568]: info: Getting Spotify volume Feb 13 01:18:51 volumio volumio[7568]: info: Connection to go-librespot Websocket closed Feb 13 01:18:51 volumio volumio[7568]: error: Cannot start Volumio Streaming Daemon Feb 13 01:18:51 volumio volumio[7568]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 01:18:51 volumio volumio[7568]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 01:18:51 volumio volumio[7568]: 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 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+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 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+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 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+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 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+07:00" level=info msg="zeroconf server listening on port 38857" Feb 13 01:18:51 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:51 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:51 volumio volumio[7568]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 13 01:18:51 volumio volumio[7568]: SPOTIFY: SPOTIFY VOLUME undefined Feb 13 01:18:51 volumio volumio[7568]: SPOTIFY: VOLUMIO VOLUME 100 Feb 13 01:18:51 volumio volumio[7568]: info: Aligning Spotify Volume to Volumio Volume Feb 13 01:18:51 volumio volumio[7568]: info: CoreCommandRouter::volumioGetState Feb 13 01:18:51 volumio volumio[7568]: info: CorePlayQueue::getTrack 0 Feb 13 01:18:51 volumio volumio[7568]: info: Setting Spotify Volume from Volumio: 100 Feb 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+07:00" level=debug msg="obtained new client token: AAAKWmDBHY3600jDJTXLWfxSeOXJM9Vp3gMhnzlGEumwIkRcG19/P5884N3jEsGdXq8TAZaIAJ+zWdgbgErae1achQy20Andwsxji2Qbu6mmzWhLPtg8YyK+xe9cAc8qAe7dKD1coBPol4dg41+YA0BbZ0h/w3ZqAFNhmd9aTMkOLOJtIh1ffK6P3wxlnMtETmecdxesW9jkbBXHjycAuz5xDM1st18pUO83zclJ/IqoIDACMYFj0NjxSg==" Feb 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+07:00" level=debug msg="completed keyexchange" Feb 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+07:00" level=debug msg="completed challenge" Feb 13 01:18:51 volumio go-librespot[7926]: time="2026-02-13T01:18:51+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 13 01:18:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 01:18:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 01:18:51 volumio volumio[7568]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:18:51 volumio volumio[7568]: Error: socket hang up Feb 13 01:18:51 volumio volumio[7568]: at connResetException (node:internal/errors:720:14) Feb 13 01:18:51 volumio volumio[7568]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 13 01:18:51 volumio volumio[7568]: at Socket.emit (node:events:526:35) Feb 13 01:18:51 volumio volumio[7568]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 13 01:18:51 volumio volumio[7568]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 13 01:18:51 volumio volumio[7568]: code: 'ECONNRESET', Feb 13 01:18:51 volumio volumio[7568]: response: undefined Feb 13 01:18:51 volumio volumio[7568]: } Feb 13 01:18:51 volumio volumio[7568]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 01:18:53 volumio sudo[7955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 01:17' Feb 13 01:18:53 volumio sudo[7955]: 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"