Feb 10 06:13:00 volumio volumio[7864]: info: Starting Shairport Sync Feb 10 06:13:00 volumio go-librespot[8104]: time="2026-02-10T06:13:00+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 10 06:13:00 volumio volumio[7864]: info: Starting Shairport Sync Feb 10 06:13:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:00 volumio volumio[7864]: info: Starting Shairport Sync Feb 10 06:13:00 volumio sudo[8133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:00 volumio sudo[8133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:00 volumio sudo[8135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:00 volumio sudo[8135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:00 volumio sudo[8137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:00 volumio sudo[8137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 06:13:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 06:13:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:00 volumio systemd[1]: shairport-sync.service: Consumed 2.295s CPU time. Feb 10 06:13:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:00 volumio sudo[8133]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:00 volumio sudo[8135]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:00 volumio sudo[8137]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:00 volumio volumio[7864]: info: Shairport-Sync Started Feb 10 06:13:00 volumio volumio[7864]: Error adding Membership: Error: addMembership EINVAL Feb 10 06:13:00 volumio volumio[7864]: info: Shairport-Sync Started Feb 10 06:13:00 volumio volumio[7864]: info: Shairport-Sync Started Feb 10 06:13:00 volumio volumio[7864]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:00 volumio volumio[7864]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:00 volumio volumio[7864]: 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 10 06:13:00 volumio volumio[7864]: info: Spotify Successfully logged in Feb 10 06:13:00 volumio volumio[7864]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:00 volumio volumio[7864]: info: [1770678780830] CoreMusicLibrary::Adding element Spotify Feb 10 06:13:00 volumio volumio[7864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:00 volumio volumio[7864]: Cannot find translation for source YouTube Music Feb 10 06:13:00 volumio volumio[7864]: Cannot find translation for source Spotify Feb 10 06:13:01 volumio volumio[7864]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 06:13:01 volumio volumio[7864]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:13:01 volumio volumio[7864]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:13:01 volumio volumio[7864]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:01 volumio volumio[7864]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:01 volumio volumio[7864]: info: CoreStateMachine::pushState Feb 10 06:13:01 volumio volumio[7864]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:01 volumio volumio[7864]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:02 volumio volumio[7864]: info: go-librespot daemon successfully initialized Feb 10 06:13:03 volumio mpd[8080]: 2026-02-10T06:13:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 06:13:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 10 06:13:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 06:13:03 volumio sudo[8049]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:03 volumio sudo[8038]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:03 volumio go-librespot[8177]: go-librespot daemon starting... Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+07:00" level=debug msg="app state loaded" Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:03 volumio volumio[7864]: error: MPD error: The expression evaluated to a falsy value: Feb 10 06:13:03 volumio volumio[7864]: assert.ok(self.idling) Feb 10 06:13:03 volumio volumio[7864]: error: The expression evaluated to a falsy value: Feb 10 06:13:03 volumio volumio[7864]: assert.ok(self.idling) Feb 10 06:13:03 volumio volumio[7864]: info: MPD running with PID8080 Feb 10 06:13:03 volumio volumio[7864]: ,establishing connection Feb 10 06:13:03 volumio volumio[7864]: error: updateQueue error: null Feb 10 06:13:03 volumio volumio[7864]: info: Completed starting Core Plugins Feb 10 06:13:03 volumio volumio[7864]: info: ------------------------------------------- Feb 10 06:13:03 volumio volumio[7864]: info: ----- MyVolumio plugins startup ---- Feb 10 06:13:03 volumio volumio[7864]: info: ------------------------------------------- Feb 10 06:13:03 volumio volumio[7864]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 06:13:03 volumio volumio[7864]: error: updateQueue error: null Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+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 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+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 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+07:00" level=info msg="zeroconf server listening on port 33053" Feb 10 06:13:03 volumio go-librespot[8180]: time="2026-02-10T06:13:03+07:00" level=debug msg="obtained new client token: AADABvmF26nnIYLH0iFmAIpVytpJc4ME9ssCn6RFC9sSWh08mYPXo8D/1AgpUFA64uhVq1y09sTybByFKp5a68cbG1tXUFwqBLP3o3rFNk65MDjLRNtZ2EzFLMeAQX7O+IChbK0+NnCB9+PnEzaSiqIOMzajhcOhR3inUIZabGD4BjP+j7SvVgEgTtD4JMY2WzkXvLt0NX9LCl3A4oFozIxl+xCKaOj9YtEvjkfCns2Cfl8RWaJL/t5lEg==" Feb 10 06:13:04 volumio go-librespot[8180]: time="2026-02-10T06:13:04+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 10 06:13:04 volumio go-librespot[8180]: time="2026-02-10T06:13:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 10 06:13:04 volumio go-librespot[8180]: time="2026-02-10T06:13:04+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:04 volumio go-librespot[8180]: time="2026-02-10T06:13:04+07:00" level=debug msg="completed challenge" Feb 10 06:13:04 volumio go-librespot[8180]: time="2026-02-10T06:13:04+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 10 06:13:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:05 volumio volumio[7864]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:05 volumio volumio[7864]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 10 06:13:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:07 volumio go-librespot[8188]: go-librespot daemon starting... Feb 10 06:13:07 volumio go-librespot[8189]: time="2026-02-10T06:13:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:07 volumio go-librespot[8189]: time="2026-02-10T06:13:07+07:00" level=debug msg="app state loaded" Feb 10 06:13:07 volumio go-librespot[8189]: time="2026-02-10T06:13:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+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 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+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 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+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 10 06:13:08 volumio volumio[7864]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+07:00" level=info msg="zeroconf server listening on port 35339" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+07:00" level=debug msg="obtained new client token: AACIbM3zx6hksERh2ykHZZz57h7RGmcAChDHgueYGP5lUHv0ZnkR+EMKw4GMieYfKOMRH+G3O4JPFuWg6sHJ+8vvaHz9/XyKgOkL+MCrhXSuCYGAaCmSshw41KCfSXu/wwQL71teWNG6qJMCmy/zX1bZtPQuvNKCuEAEnHsCFT/32mLReBPbpgIjn9VQSPeH+mgjfbPZ103BCwUqMdKI9p1MTUiOIc9J81uHyoJtJDVqmTGCtEs33/ivFQ==" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+07:00" level=debug msg="completed challenge" Feb 10 06:13:08 volumio go-librespot[8189]: time="2026-02-10T06:13:08+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 10 06:13:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:08 volumio volumio[7864]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:08 volumio volumio[7864]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 10 06:13:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:11 volumio go-librespot[8196]: go-librespot daemon starting... Feb 10 06:13:11 volumio go-librespot[8197]: time="2026-02-10T06:13:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:11 volumio go-librespot[8197]: time="2026-02-10T06:13:11+07:00" level=debug msg="app state loaded" Feb 10 06:13:11 volumio go-librespot[8197]: time="2026-02-10T06:13:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:11 volumio volumio[7864]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:11 volumio go-librespot[8197]: time="2026-02-10T06:13:11+07:00" level=debug msg="new websocket client" Feb 10 06:13:11 volumio volumio[7864]: info: Connection to go-librespot Websocket established Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 06:13:12 volumio volumio[7864]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+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 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+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 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+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 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+07:00" level=info msg="zeroconf server listening on port 35189" Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+07:00" level=debug msg="obtained new client token: AAC6Awaaxqo3uNn568tZUHaOPkQ5GBQEkwQwaM9EGrg9PClHPTnGNUg+xvmNS/u/uj8IFQSRCEepcCG6oZrcuz2RPtsKMrrxUSEnQS6bzfrntTNGGq8sK4bjDFqHeEzPY0BzmukaInT3HJ+in7tufgM1q3YGfcoSfQRuVLwS13Kadw8LZleAq2+L76By1uYD86NvYdqfvyvQVaLIUZMhDswE0xU2t2d3Ok5BdDUEH1RmpbRNOGsHfiIyxQ==" Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13:12+07:00" level=debug msg="completed challenge" Feb 10 06:13:12 volumio go-librespot[8197]: time="2026-02-10T06:13: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 10 06:13:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:14 volumio volumio[7864]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 06:13:14 volumio volumio[7864]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 06:13:14 volumio volumio[7864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:14 volumio volumio[7864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:14 volumio volumio[7864]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 06:13:14 volumio volumio[7864]: info: MyVolumio login type: Token Feb 10 06:13:14 volumio volumio[7864]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 06:13:14 volumio volumio[7864]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 06:13:15 volumio volumio[7864]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 06:13:15 volumio volumio[7864]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 06:13:15 volumio volumio[7864]: info: Streaming services startup Feb 10 06:13:15 volumio volumio[7864]: info: Starting Streaming Daemon Feb 10 06:13:15 volumio sudo[8222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 06:13:15 volumio sudo[8222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:15 volumio volumio[7864]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 06:13:15 volumio sudo[8222]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 10 06:13:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:16 volumio volumio[7864]: info: Getting Spotify volume Feb 10 06:13:16 volumio volumio[7864]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:13:16 volumio volumio[7864]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:16 volumio volumio[7864]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 10 06:13:16 volumio volumio[7864]: errno: -111, Feb 10 06:13:16 volumio volumio[7864]: code: 'ECONNREFUSED', Feb 10 06:13:16 volumio volumio[7864]: syscall: 'connect', Feb 10 06:13:16 volumio volumio[7864]: address: '127.0.0.1', Feb 10 06:13:16 volumio volumio[7864]: port: 9879, Feb 10 06:13:16 volumio volumio[7864]: response: undefined Feb 10 06:13:16 volumio volumio[7864]: } Feb 10 06:13:16 volumio volumio[7864]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:13:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:16 volumio go-librespot[8228]: go-librespot daemon starting... Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+07:00" level=debug msg="app state loaded" Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+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 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+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 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+07:00" level=info msg="zeroconf server listening on port 35421" Feb 10 06:13:16 volumio go-librespot[8231]: time="2026-02-10T06:13:16+07:00" level=debug msg="obtained new client token: AADs7E/nbDH7xy7ByZUNkHiEmsFClJ5l1f4obIo1KhNkR6Ei35tZexr7xJpIT9t0u+6gmCMUTJexXMY/iWQ4PaVfxaDLEowI1u3b96RVtR0A7sgrU8GETPuQFjg9X9q9wbIDGYmXN1piAYlEIQArpyr1EaYywsxnbcofYa6183Q6ph76WvpThfo/xjhko11FAezOkHrcLgywDJnZ0Jky6BYDXHFjeuaP+jyz4AUQPzQZ7BJ5rek8MqQrOA==" Feb 10 06:13:17 volumio go-librespot[8231]: time="2026-02-10T06:13:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:17 volumio go-librespot[8231]: time="2026-02-10T06:13:17+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:17 volumio go-librespot[8231]: time="2026-02-10T06:13:17+07:00" level=debug msg="completed challenge" Feb 10 06:13:17 volumio go-librespot[8231]: time="2026-02-10T06:13:17+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 10 06:13:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:17 volumio sudo[8250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 06:12' Feb 10 06:13:17 volumio sudo[8250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:17 volumio sudo[8250]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:19 volumio volumio-remote-updater[643]: [2026-02-10 06:13:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 10 06:13:19 volumio volumio-remote-updater[643]: [2026-02-10 06:13:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 10 06:13:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 10 06:13:19 volumio systemd[1]: volumio.service: Consumed 55.219s CPU time. Feb 10 06:13:19 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 06:13:19 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 06:13:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9825. Feb 10 06:13:19 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 06:13:19 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 10 06:13:19 volumio systemd[1]: volumio.service: Consumed 55.219s CPU time. Feb 10 06:13:19 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 06:13:19 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 06:13:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 10 06:13:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:20 volumio go-librespot[8280]: go-librespot daemon starting... Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=debug msg="app state loaded" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 06:13:20 volumio go-librespot[8281]: time="2026-02-10T06:13:20+07:00" level=info msg="zeroconf server listening on port 39099" Feb 10 06:13:21 volumio go-librespot[8281]: time="2026-02-10T06:13:21+07:00" level=debug msg="obtained new client token: AAAbb7ts5OCqkq+MMrsb4onCtQ04cRXY4GwL4y+n2EUuLE8iEK09ydjFq7u0bwcN8SHGPQyt3KR9Q+J69s83abXnWzNaPVldxVe5JqZYDHwaepF8BxE2fp9TsBTmJS6Q3aO+d2bwSQKC6Lcqrzf8zFzor31JudhtN1gnz5yd8ruTizNAhf/AcWKEbnplTtjX1y9Pj1dui5z5KO+DdyZU0arSs9WiqQQZ1ys4Zoug2hd3GTKBC3RNJ58=" Feb 10 06:13:21 volumio go-librespot[8281]: time="2026-02-10T06:13:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:21 volumio go-librespot[8281]: time="2026-02-10T06:13:21+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:21 volumio go-librespot[8281]: time="2026-02-10T06:13:21+07:00" level=debug msg="completed challenge" Feb 10 06:13:21 volumio go-librespot[8281]: time="2026-02-10T06:13: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 10 06:13:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:23 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:23 volumio volumio[8265]: info: ----- Volumio3 ---- Feb 10 06:13:23 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:23 volumio volumio[8265]: info: ----- System startup ---- Feb 10 06:13:23 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:24 volumio volumio-remote-updater[643]: [2026-02-10 06:13:24] [connect] Successful connection Feb 10 06:13:24 volumio volumio[8265]: info: MYVOLUMIO Environment detected Feb 10 06:13:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 10 06:13:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:24 volumio volumio[8265]: info: Plugin folders cleanup Feb 10 06:13:24 volumio volumio[8265]: info: Scanning into folder /volumio/app/plugins/ Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category audio_interface Feb 10 06:13:24 volumio go-librespot[8297]: go-librespot daemon starting... Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category miscellanea Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category music_service Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category plugins.json Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category system_controller Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category user_interface Feb 10 06:13:24 volumio volumio[8265]: info: Scanning into folder /data/plugins/ Feb 10 06:13:24 volumio volumio[8265]: info: Scanning category music_service Feb 10 06:13:24 volumio volumio[8265]: info: Plugin folders cleanup completed Feb 10 06:13:24 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:24 volumio volumio[8265]: info: ----- Core plugins startup ---- Feb 10 06:13:24 volumio go-librespot[8298]: time="2026-02-10T06:13:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:24 volumio go-librespot[8298]: time="2026-02-10T06:13:24+07:00" level=debug msg="app state loaded" Feb 10 06:13:24 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:24 volumio volumio[8265]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 06:13:24 volumio go-librespot[8298]: time="2026-02-10T06:13:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:24 volumio volumio[8265]: info: Adding plugin upnp to MyMusic Plugins Feb 10 06:13:24 volumio volumio[8265]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 06:13:24 volumio volumio[8265]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 06:13:24 volumio volumio[8265]: info: Loading plugins from folder /data/plugins/ Feb 10 06:13:24 volumio volumio[8265]: info: Loading plugin "system"... Feb 10 06:13:24 volumio volumio[8265]: info: Loading plugin "appearance"... Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13: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 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13: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 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13: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 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13:25+07:00" level=info msg="zeroconf server listening on port 33757" Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13:25+07:00" level=debug msg="obtained new client token: AAAuTT+ovqxdTFRXQixYRnV/v4oHanulBdcXJ1Dix0XngQ8vPW8kiYyTXeqeMx8KQQ/14GnAwwt8tK6bboBAVpPXVmuaJJhGLtaC41eUq44sYnDdQzWgTxHYajQfHJnbg/VSVnZ6+AYXim/dj4o4BpQHAMX2VxnUnl/7zkIs1BCY3Z/sXLrushgHTd5L4toks1g1jbEChQ/suMQIwrq6guUFt9Qf0YCQ+JrdnzMNg2t6r+hS49EvnSas7w==" Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13:25+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13:25+07:00" level=debug msg="completed challenge" Feb 10 06:13:25 volumio go-librespot[8298]: time="2026-02-10T06:13: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 10 06:13:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:26 volumio volumio[8265]: info: Loading plugin "network"... Feb 10 06:13:26 volumio volumio[8265]: info: Refreshing Cached IP Addresses Feb 10 06:13:26 volumio sudo[8310]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 06:13:26 volumio sudo[8310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:26 volumio sudo[8312]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 06:13:26 volumio sudo[8312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:26 volumio volumio[8265]: info: Loading plugin "services"... Feb 10 06:13:26 volumio sudo[8310]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:26 volumio volumio[8265]: info: Loading plugin "alsa_controller"... Feb 10 06:13:26 volumio sudo[8312]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:26 volumio sudo[8320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 06:13:26 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:26 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:13:26 volumio volumio[8265]: info: Loading plugin "wizard"... Feb 10 06:13:26 volumio volumio[8265]: info: Loading plugin "networkfs"... Feb 10 06:13:27 volumio volumio[8265]: info: Starting Udev Watcher for removable devices Feb 10 06:13:27 volumio volumio[8265]: info: Ignoring mount for partition: boot Feb 10 06:13:27 volumio volumio[8265]: info: Ignoring mount for partition: volumio Feb 10 06:13:27 volumio volumio[8265]: info: Ignoring mount for partition: volumio_data Feb 10 06:13:27 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:13:27 volumio volumio[8265]: info: Loading plugin "volumio_command_line_client"... Feb 10 06:13:27 volumio volumio[8265]: info: Loading plugin "upnp"... Feb 10 06:13:27 volumio volumio[8265]: info: [1770678807093] Starting Upmpd Daemon Feb 10 06:13:27 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:13:27 volumio volumio[8265]: info: Loading plugin "my_music"... Feb 10 06:13:27 volumio volumio[8265]: info: Loading plugin "mpd"... Feb 10 06:13:27 volumio volumio[8265]: info: Loading plugin "upnp_browser"... Feb 10 06:13:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 10 06:13:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:28 volumio go-librespot[8343]: go-librespot daemon starting... Feb 10 06:13:28 volumio go-librespot[8344]: time="2026-02-10T06:13:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:28 volumio go-librespot[8344]: time="2026-02-10T06:13:28+07:00" level=debug msg="app state loaded" Feb 10 06:13:28 volumio go-librespot[8344]: time="2026-02-10T06:13:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=info msg="zeroconf server listening on port 43433" Feb 10 06:13:29 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="obtained new client token: AABsGd+zdXDvfNKYAPgSKadJ49LThQ7+kbvsyYt86TUueUQ6hqgFOAvuVahsHsBRnxLwrXvaPtGBFjqw4wV7Lb3YLMqvZvcYaUKRTBDljb3t66tmvf8dH1+YLCkDRWJDTVvgQ9+ZyLP8AKMqK01y2fIDREPOlHr2ieM8iqMT8RZw+Km3lHYGMyMH6dTd0DQJjBxHlrAqfYeYMkxhzZ+R0aalTY7Eu1lsUl+IwVX3ur+rMXpxrhgk0RcdKw==" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:29 volumio go-librespot[8344]: time="2026-02-10T06:13:29+07:00" level=debug msg="completed challenge" Feb 10 06:13:30 volumio go-librespot[8344]: time="2026-02-10T06:13: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 10 06:13:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:30 volumio volumio[8265]: info: Starting UPNP Browser Feb 10 06:13:30 volumio volumio[8265]: info: Loading plugin "alarm-clock"... Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "airplay_emulation"... Feb 10 06:13:31 volumio volumio[8265]: info: Starting Shairport Sync Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "last_100"... Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "webradio"... Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "i2s_dacs"... Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "volumiodiscovery"... Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** For more information see Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:13:31 volumio volumio[8265]: *** WARNING *** For more information see Feb 10 06:13:31 volumio node[8265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 06:13:31 volumio node[8265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:13:31 volumio node[8265]: *** WARNING *** For more information see Feb 10 06:13:31 volumio node[8265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 06:13:31 volumio node[8265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:13:31 volumio node[8265]: *** WARNING *** For more information see Feb 10 06:13:31 volumio volumio[8265]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 06:13:31 volumio volumio[8265]: info: Discovery: Started advertising with name: Volumio Feb 10 06:13:31 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:13:31 volumio volumio[8265]: info: Loading plugin "spop"... Feb 10 06:13:33 volumio volumio[8265]: info: Loading plugin "ytcr"... Feb 10 06:13:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 10 06:13:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:33 volumio go-librespot[8354]: go-librespot daemon starting... Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=debug msg="app state loaded" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 06:13:33 volumio go-librespot[8355]: time="2026-02-10T06:13:33+07:00" level=info msg="zeroconf server listening on port 44323" Feb 10 06:13:34 volumio go-librespot[8355]: time="2026-02-10T06:13:34+07:00" level=debug msg="obtained new client token: AADoH5/SgVfc1PbCq8Rn4dX2N5zBK3BenE1bWLNyCr4MrnFSssk9uiztJrkS1znx8bKXFyBVCr11z84K6RHus0/1IFpCWJzMYtpt3NUl5diPLt0NXUJJW21YVM0FZg958Z2vXLmUE6d6hgADStP2eafHWlv/dMpfSUJyxk0EVCBGSG+VyhxaTPyL4b4L43Z+p90CWPj7iUbbpI6qSogb7rPy8ewpTLK2YI1nKL+WxsCCccXiWQcidBc=" Feb 10 06:13:34 volumio go-librespot[8355]: time="2026-02-10T06:13:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:34 volumio go-librespot[8355]: time="2026-02-10T06:13:34+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:34 volumio go-librespot[8355]: time="2026-02-10T06:13:34+07:00" level=debug msg="completed challenge" Feb 10 06:13:34 volumio go-librespot[8355]: time="2026-02-10T06:13: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 10 06:13:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:35 volumio volumio[8265]: info: Loading plugin "ytmusic"... Feb 10 06:13:36 volumio volumio-remote-updater[643]: [2026-02-10 06:13:36] [connect] Successful connection Feb 10 06:13:36 volumio volumio[8265]: info: Loading plugin "outputs"... Feb 10 06:13:36 volumio volumio[8265]: info: Loading plugin "albumart"... Feb 10 06:13:37 volumio volumio[8265]: info: Plugin example_plugin is not enabled Feb 10 06:13:37 volumio volumio[8265]: info: Loading plugin "inputs"... Feb 10 06:13:37 volumio volumio[8265]: info: Loading plugin "updater_comm"... Feb 10 06:13:37 volumio volumio[8265]: info: Plugin mpdemulation is not enabled Feb 10 06:13:37 volumio volumio[8265]: info: Loading plugin "rest_api"... Feb 10 06:13:37 volumio volumio[8265]: info: Loading plugin "websocket"... Feb 10 06:13:37 volumio volumio[8265]: info: Starting Socket.io Server version 1.7.4 Feb 10 06:13:37 volumio volumio[8265]: info: Loading plugin "RoonBridge"... Feb 10 06:13:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 10 06:13:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:37 volumio go-librespot[8386]: go-librespot daemon starting... Feb 10 06:13:37 volumio go-librespot[8387]: time="2026-02-10T06:13:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:37 volumio go-librespot[8387]: time="2026-02-10T06:13:37+07:00" level=debug msg="app state loaded" Feb 10 06:13:37 volumio go-librespot[8387]: time="2026-02-10T06:13:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:37 volumio volumio[8265]: info: Applying required configuration parameters for plugin RoonBridge Feb 10 06:13:37 volumio volumio[8265]: info: Loading i18n strings for locale en Feb 10 06:13:37 volumio volumio[8265]: Updating browse sources language Feb 10 06:13:37 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:38 volumio volumio[8376]: Forking 3 albumart workers Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::initPlayerControls Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13: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 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13: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 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13: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 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13:38+07:00" level=info msg="zeroconf server listening on port 43823" Feb 10 06:13:38 volumio volumio[8265]: Express server listening on port 3000 Feb 10 06:13:38 volumio volumio[8265]: [Metrics] WebUI: 16s 72.12ms Feb 10 06:13:38 volumio volumio[8265]: info: CoreStateMachine::resetVolumioState Feb 10 06:13:38 volumio volumio[8265]: info: CoreStateMachine::getcurrentVolume Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:13:38 volumio sudo[8427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 06:13:38 volumio sudo[8427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:38 volumio sudo[8427]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13:38+07:00" level=debug msg="obtained new client token: AABL0wwQTc+fFROyZkMUakqlFaQRGC5kmYElLH+sFmRWCSS3cyuehYxTytroGCef+Zrzx3haG6sTjzyXvf7hwRrgxfWKLlZ9LLZBAcJdF+w/yEHUx/dmCFNOCq7dmO2TQOMTgo46VRRjrd0aWdHUTF50nkfttsFiBJ7ydUyrvrurAELP7CMySN7+/ji6alKqleq3Voimp5GW2lx6FtTIj1c4WanT85/SiRXT9XhdMjdFhKd1uP3EZVOoSQ==" Feb 10 06:13:38 volumio sudo[8430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 06:13:38 volumio sudo[8430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:38 volumio sudo[8430]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:38 volumio volumio[8265]: info: Volumio Network Manager: Network status updated: 1 Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13:38+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13:38+07:00" level=debug msg="completed challenge" Feb 10 06:13:38 volumio go-librespot[8387]: time="2026-02-10T06:13: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 10 06:13:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:38 volumio volumio[8265]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:13:38 volumio volumio[8265]: info: CoreStateMachine::pushState Feb 10 06:13:38 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:38 volumio volumio[8265]: info: CoreStateMachine::updateTrackBlock Feb 10 06:13:38 volumio volumio[8265]: info: CorePlayQueue::getTrackBlock Feb 10 06:13:38 volumio volumio[8265]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:13:39 volumio volumio-remote-updater[643]: [2026-02-10 06:13:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770678816 101 Feb 10 06:13:39 volumio volumio[8265]: 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 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:39 volumio volumio[8265]: info: Reloading queue from file Feb 10 06:13:39 volumio volumio[8265]: info: CoreStateMachine::setRepeat null single undefined Feb 10 06:13:39 volumio volumio[8265]: info: CoreStateMachine::pushState Feb 10 06:13:39 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:39 volumio volumio[8265]: info: CoreStateMachine::setRandom null Feb 10 06:13:39 volumio volumio[8265]: info: CoreStateMachine::pushState Feb 10 06:13:39 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:39 volumio volumio[8265]: info: Setting Device type: Raspberry PI Feb 10 06:13:39 volumio volumio[8265]: info: Completed loading Core Plugins Feb 10 06:13:39 volumio volumio[8265]: info: Preparing to generate the ALSA configuration file Feb 10 06:13:39 volumio volumio[8265]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:13:39 volumio volumio[8265]: info: CoreStateMachine::pushState Feb 10 06:13:39 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:39 volumio volumio[8265]: info: Asound.conf file unchanged, so no further update is needed Feb 10 06:13:39 volumio volumio[8265]: info: Output device has changed, restarting MPD Feb 10 06:13:39 volumio volumio[8265]: info: Output device has changed, restarting Shairport Sync Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:39 volumio sudo[8445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 06:13:39 volumio sudo[8445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:39 volumio sudo[8447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 06:13:39 volumio sudo[8447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:39 volumio sudo[8447]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:39 volumio sudo[8449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 06:13:39 volumio sudo[8449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:39 volumio volumio[8265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:13:39 volumio volumio[8265]: info: ___________ START PLUGINS ___________ Feb 10 06:13:39 volumio volumio[8265]: info: ControllerMpd::onStart: Initializing MPD Feb 10 06:13:39 volumio sudo[8445]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:39 volumio volumio[8265]: info: Creating MPD Configuration file Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:13:39 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:39 volumio volumio[8265]: info: [1770678819665] CoreMusicLibrary::Adding element Media Servers Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:39 volumio sudo[8458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 06:13:39 volumio sudo[8458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:39 volumio sudo[8458]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:39 volumio volumio[8265]: info: UPNP Browser: Client initialized successfully Feb 10 06:13:39 volumio sudo[8461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 06:13:39 volumio sudo[8461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:39 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 06:13:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 06:13:39 volumio systemd[1]: mpd.service: Consumed 7.174s CPU time. Feb 10 06:13:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 06:13:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 06:13:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:39 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 06:13:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 06:13:40 volumio volumio[8265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:40 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 06:13:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 06:13:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 06:13:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 06:13:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 06:13:40 volumio volumio[8265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:40 volumio volumio[8265]: info: [1770678820091] CoreMusicLibrary::Adding element Last_100 Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:40 volumio volumio[8265]: info: [1770678820109] CoreMusicLibrary::Adding element Webradio Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:13:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 06:13:40 volumio volumio[8265]: info: Initializing BBC Radios Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:40 volumio volumio[8265]: info: Creating Spotify config file Feb 10 06:13:40 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:40 volumio sudo[8474]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 06:13:40 volumio sudo[8474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 06:13:40 volumio sudo[8474]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:41 volumio volumio[8396]: Starting albumart workers Feb 10 06:13:41 volumio volumio[8394]: Starting albumart workers Feb 10 06:13:41 volumio volumio[8395]: Starting albumart workers Feb 10 06:13:41 volumio volumio[8265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:41 volumio volumio[8265]: info: [1770678821937] CoreMusicLibrary::Adding element YouTube Music Feb 10 06:13:41 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:41 volumio volumio[8265]: Cannot find translation for source YouTube Music Feb 10 06:13:41 volumio volumio[8265]: info: Volumio Calling Home Feb 10 06:13:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 10 06:13:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:42 volumio sudo[8492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 10 06:13:42 volumio sudo[8492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:42 volumio go-librespot[8494]: go-librespot daemon starting... Feb 10 06:13:42 volumio go-librespot[8496]: time="2026-02-10T06:13:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:42 volumio sudo[8492]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:43 volumio go-librespot[8496]: time="2026-02-10T06:13:43+07:00" level=info msg="zeroconf server listening on port 36191" Feb 10 06:13:43 volumio volumio[8265]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 06:13:43 volumio volumio[8265]: info: Discovery: Found device Volumio Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:43 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:43 volumio volumio[8265]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 06:13:43 volumio volumio[8265]: info: Discovery: Found device Volumio Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:43 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:43 volumio volumio[8265]: info: MPD Permissions set Feb 10 06:13:43 volumio volumio[8265]: info: MPD Permissions set Feb 10 06:13:43 volumio volumio[8265]: info: Upmpdcli Daemon Started Feb 10 06:13:43 volumio volumio[8265]: info: Volumio called home Feb 10 06:13:43 volumio volumio[8265]: info: Spotify config file written Feb 10 06:13:43 volumio volumio[8265]: 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 10 06:13:43 volumio sudo[8512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 06:13:43 volumio sudo[8512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 10 06:13:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:13:43 volumio volumio[8265]: info: No need to fix Spotify hosts Feb 10 06:13:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:43 volumio go-librespot[8527]: go-librespot daemon starting... Feb 10 06:13:43 volumio sudo[8512]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:43 volumio go-librespot[8532]: time="2026-02-10T06:13:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:43 volumio go-librespot[8532]: time="2026-02-10T06:13:43+07:00" level=debug msg="app state loaded" Feb 10 06:13:43 volumio go-librespot[8532]: time="2026-02-10T06:13:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+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 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+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 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=info msg="zeroconf server listening on port 44987" Feb 10 06:13:44 volumio volumio[8265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 06:13:44 volumio volumio[8265]: SPOTIFY: BQCE1lB5GEMozBgudmgrGeWkXmaLoWKRkWG2JHXu7eJrKH3KISdfCaLNW2l-GuKhXA-dE2AwTf-oKFOea3o3RKhKzl9isv8KlJ9rwppU4U9ByujoFPin2lo_jyBd9yFD9QDnYaeJIFghA_mJIa6p8gAMqRL4NrlH5oCHWweiBkRQJYzPQFU28d7xlyMHwjjjWGaq7Nr0Zlqr4c8zk8Cppse7F8Ua50LWC-DWpsnMBoEDLw23-8Pd8kTl2BpJ3gQaJpZ7rXo51QDBy4bNn96zulEkqmB7pJofkI4yDloVlU8TGqooh-CWYh_U Feb 10 06:13:44 volumio volumio[8265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 06:13:44 volumio volumio[8265]: info: New Spotify access token = BQCE1lB5GEMozBgudmgrGeWkXmaLoWKRkWG2JHXu7eJrKH3KISdfCaLNW2l-GuKhXA-dE2AwTf-oKFOea3o3RKhKzl9isv8KlJ9rwppU4U9ByujoFPin2lo_jyBd9yFD9QDnYaeJIFghA_mJIa6p8gAMqRL4NrlH5oCHWweiBkRQJYzPQFU28d7xlyMHwjjjWGaq7Nr0Zlqr4c8zk8Cppse7F8Ua50LWC-DWpsnMBoEDLw23-8Pd8kTl2BpJ3gQaJpZ7rXo51QDBy4bNn96zulEkqmB7pJofkI4yDloVlU8TGqooh-CWYh_U Feb 10 06:13:44 volumio volumio[8265]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=debug msg="obtained new client token: AACHyfqh3f1fTyD4AhMxXSntUG1oAfLi0s9W9DrAA2UcMt9hvMBV5fZKxv2s9RsdJKbeIpI35k8NQs8Lo9wPHDMSRaNbETm8pQ+oYTUUctlUwhpSXvWO1/kUiZnaji6OQpXsgl+k/zTbsGtN2/X1hqo7LV8Tlr4HGLkEHuk4WLqEyPDeK5tzLiVMkhe5iZJJM2ihRSc6+//O53uLpfmDvEiLDhpe4/ALvu3C+bgRnPjmiGEVi0EVzoYidw==" Feb 10 06:13:44 volumio volumio[8265]: info: Starting Shairport Sync Feb 10 06:13:44 volumio volumio[8265]: info: Starting Shairport Sync Feb 10 06:13:44 volumio volumio[8265]: info: Starting Shairport Sync Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:44 volumio sudo[8546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:44 volumio sudo[8546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:44 volumio sudo[8550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:44 volumio sudo[8550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:44 volumio sudo[8548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:44 volumio sudo[8548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+07:00" level=debug msg="completed challenge" Feb 10 06:13:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 06:13:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 06:13:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:44 volumio systemd[1]: shairport-sync.service: Consumed 2.310s CPU time. Feb 10 06:13:44 volumio go-librespot[8532]: time="2026-02-10T06:13:44+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 10 06:13:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:44 volumio sudo[8550]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:44 volumio sudo[8546]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 06:13:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 06:13:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:13:45 volumio sudo[8548]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:45 volumio volumio[8265]: info: Shairport-Sync Started Feb 10 06:13:45 volumio volumio[8265]: Error adding Membership: Error: addMembership EINVAL Feb 10 06:13:45 volumio volumio[8265]: info: Shairport-Sync Started Feb 10 06:13:45 volumio volumio[8265]: info: Shairport-Sync Started Feb 10 06:13:45 volumio volumio[8265]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:45 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:45 volumio volumio[8265]: 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 10 06:13:45 volumio volumio[8265]: info: Spotify Successfully logged in Feb 10 06:13:45 volumio volumio[8265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:13:45 volumio volumio[8265]: info: [1770678825474] CoreMusicLibrary::Adding element Spotify Feb 10 06:13:45 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:13:45 volumio volumio[8265]: Cannot find translation for source YouTube Music Feb 10 06:13:45 volumio volumio[8265]: Cannot find translation for source Spotify Feb 10 06:13:45 volumio volumio[8265]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 06:13:46 volumio volumio[8265]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:13:46 volumio volumio[8265]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:13:46 volumio volumio[8265]: info: CoreCommandRouter::volumioGetState Feb 10 06:13:46 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:46 volumio volumio[8265]: info: CoreStateMachine::pushState Feb 10 06:13:46 volumio volumio[8265]: info: CorePlayQueue::getTrack 0 Feb 10 06:13:46 volumio volumio[8265]: info: CoreCommandRouter::volumioPushState Feb 10 06:13:47 volumio volumio[8265]: info: go-librespot daemon successfully initialized Feb 10 06:13:47 volumio mpd[8489]: 2026-02-10T06:13:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 06:13:47 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 06:13:47 volumio sudo[8461]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:47 volumio sudo[8449]: pam_unix(sudo:session): session closed for user root Feb 10 06:13:47 volumio volumio[8265]: error: MPD error: The expression evaluated to a falsy value: Feb 10 06:13:47 volumio volumio[8265]: assert.ok(self.idling) Feb 10 06:13:47 volumio volumio[8265]: error: The expression evaluated to a falsy value: Feb 10 06:13:47 volumio volumio[8265]: assert.ok(self.idling) Feb 10 06:13:48 volumio volumio[8265]: error: updateQueue error: null Feb 10 06:13:48 volumio volumio[8265]: info: MPD running with PID8489 Feb 10 06:13:48 volumio volumio[8265]: ,establishing connection Feb 10 06:13:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 10 06:13:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:48 volumio volumio[8265]: info: Completed starting Core Plugins Feb 10 06:13:48 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:48 volumio volumio[8265]: info: ----- MyVolumio plugins startup ---- Feb 10 06:13:48 volumio volumio[8265]: info: ------------------------------------------- Feb 10 06:13:48 volumio volumio[8265]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 06:13:48 volumio volumio[8265]: error: updateQueue error: null Feb 10 06:13:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:48 volumio go-librespot[8592]: go-librespot daemon starting... Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="app state loaded" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=info msg="zeroconf server listening on port 44321" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="obtained new client token: AAC7SrLsSF3P1CHLlwZHBrYdE4d71IGL6WXiz+70GZvbTT6DDK2I6pezg2E0BZNm2E9kU8+Cl3uRMWrIgfCVgY5jRI3jwCOATTEGcU4vprMJxNMY2By2l+aqwNv9LhGzE+M2Y19FvbRnrtxdZZVqBIW614nWKnNYlXypvpEL6s/qpr11f8RGRaBdBiOTfo/LaiIUH72abPyJYu/XKyQppkmH7F92NSFbQ45f4pQPnPLelvYO3kEduE7k+w==" Feb 10 06:13:48 volumio go-librespot[8593]: time="2026-02-10T06:13:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:49 volumio go-librespot[8593]: time="2026-02-10T06:13:49+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:49 volumio go-librespot[8593]: time="2026-02-10T06:13:49+07:00" level=debug msg="completed challenge" Feb 10 06:13:49 volumio go-librespot[8593]: time="2026-02-10T06:13:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 06:13:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:50 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:50 volumio volumio[8265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 10 06:13:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:52 volumio go-librespot[8600]: go-librespot daemon starting... Feb 10 06:13:52 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+07:00" level=debug msg="app state loaded" Feb 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:52 volumio volumio[8265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+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 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+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 10 06:13:52 volumio go-librespot[8601]: time="2026-02-10T06:13:52+07:00" level=info msg="zeroconf server listening on port 45187" Feb 10 06:13:53 volumio go-librespot[8601]: time="2026-02-10T06:13:53+07:00" level=debug msg="obtained new client token: AAAuqSUlZopYaAPcYISHFHG+Qne5jD/23FQz3c3EXeWxMR4euM7ecLikCC/Gq8PJwaW02W1inxOTHpHqixKxyYuZPLbnQYrpBqTXqo43KNoNNypzkzIcy6owx9F6aMd2wPTZcPKvGs8vNdOSReKDFGAYYkdPWoLqYa49iQwAgl3PDfOT4lzHdb3ZHxXz/mMKcviw5v0bVyvSFuXJS6t2WPJ+c6FDaDd0uDVoS4zO4HNjSAHnh01omuY=" Feb 10 06:13:53 volumio go-librespot[8601]: time="2026-02-10T06:13:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:13:53 volumio go-librespot[8601]: time="2026-02-10T06:13:53+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:53 volumio go-librespot[8601]: time="2026-02-10T06:13:53+07:00" level=debug msg="completed challenge" Feb 10 06:13:53 volumio go-librespot[8601]: time="2026-02-10T06:13:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 06:13:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:53 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:53 volumio volumio[8265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:54 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 10 06:13:54 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 10 06:13:54 volumio systemd[1]: setdatetime-helper.service: Consumed 1.781s CPU time. Feb 10 06:13:56 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:13:56 volumio volumio[8265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:13:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 10 06:13:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:13:56 volumio go-librespot[8633]: go-librespot daemon starting... Feb 10 06:13:56 volumio go-librespot[8634]: time="2026-02-10T06:13:56+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:13:56 volumio go-librespot[8634]: time="2026-02-10T06:13:56+07:00" level=debug msg="app state loaded" Feb 10 06:13:56 volumio go-librespot[8634]: time="2026-02-10T06:13:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 06:13:56 volumio volumio[8265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+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 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+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 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+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 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+07:00" level=info msg="zeroconf server listening on port 41091" Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+07:00" level=debug msg="obtained new client token: AAC52V/sXzKPIhlhR0DJQ+mNcZ3/NhMNWagjkmWsPNTvDQja/Dp2C1DdJeWWXRwQs/aGMh75bFofQSWuglXCL6LANsVKhOT4iS5yWeIYRwXK5RuUkgWwumr523HNh4c/haHZ1yuraBMA9p9Z3bIO7KyGaGLGEJFLW+DOhb2xK53VyZlenL6ZfQRuNogYdbrb9b+99at3A2rH+CfRpvzTXqiuLxKQQxlWeLfSBUm/XloEuKHa+kyvpsBYiQ==" Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+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 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+07:00" level=debug msg="completed keyexchange" Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+07:00" level=debug msg="completed challenge" Feb 10 06:13:57 volumio go-librespot[8634]: time="2026-02-10T06:13:57+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 10 06:13:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:13:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:13:58 volumio volumio[8265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 06:13:58 volumio volumio[8265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 06:13:58 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:58 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:13:58 volumio volumio[8265]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 06:13:58 volumio volumio[8265]: info: MyVolumio login type: Token Feb 10 06:13:58 volumio volumio[8265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 06:13:58 volumio volumio[8265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 06:14:00 volumio volumio[8265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 06:14:00 volumio volumio[8265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 06:14:00 volumio volumio[8265]: info: Streaming services startup Feb 10 06:14:00 volumio volumio[8265]: info: Starting Streaming Daemon Feb 10 06:14:00 volumio sudo[8656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 06:14:00 volumio sudo[8656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:00 volumio volumio[8265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 06:14:00 volumio sudo[8656]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:00 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:00 volumio volumio[8265]: error: Cannot start Volumio Streaming Daemon Feb 10 06:14:00 volumio volumio[8265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 06:14:00 volumio volumio[8265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 06:14:00 volumio volumio[8265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:14:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 10 06:14:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:01 volumio go-librespot[8662]: go-librespot daemon starting... Feb 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+07:00" level=debug msg="app state loaded" Feb 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:01 volumio volumio[8265]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+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 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+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 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+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 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+07:00" level=info msg="zeroconf server listening on port 45561" Feb 10 06:14:01 volumio go-librespot[8663]: time="2026-02-10T06:14:01+07:00" level=debug msg="obtained new client token: AAAO9+EeZEZEnFJlQBelv+bJ71jpylt+ARTn8Gd4WnSq3Im3qk4a2FNThSMJNt3TbKRq+uRE/vGGy+LegE5gZIfMXhYdNZynwam8mDNAdBcVATx0xuO7XaHYNawurUqQARBipiE2NTY8+uxPIgVsqULEb21aM8le6FGfOrqlU2GDk546zEgfJZvW2xbtJYkpsESccFSi3t7p4ftixUzQyI8lMxbkYTVc4I2nm2z+T0+xiGZPVbP/Fq7Dfg==" Feb 10 06:14:02 volumio go-librespot[8663]: time="2026-02-10T06:14:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:02 volumio volumio[8265]: info: MyVolumio token set successfully Feb 10 06:14:02 volumio volumio[8265]: info: MYVOLUMIO: Adding device Feb 10 06:14:02 volumio volumio[8265]: info: MYVOLUMIO: Evaluating Server Feb 10 06:14:02 volumio go-librespot[8663]: time="2026-02-10T06:14:02+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:02 volumio go-librespot[8663]: time="2026-02-10T06:14:02+07:00" level=debug msg="completed challenge" Feb 10 06:14:02 volumio go-librespot[8663]: time="2026-02-10T06:14:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 06:14:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:03 volumio volumio[8265]: info: MyVolumio status changed Feb 10 06:14:03 volumio volumio[8265]: info: Streaming services startup Feb 10 06:14:03 volumio volumio[8265]: info: Starting Streaming Daemon Feb 10 06:14:03 volumio volumio[8265]: info: Removing browser output: myVolumio user plan is not superstar Feb 10 06:14:03 volumio volumio[8265]: info: Removing audio output: Feb 10 06:14:03 volumio volumio[8265]: info: Stoppping Tunnel 1 Feb 10 06:14:03 volumio sudo[8692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 06:14:03 volumio sudo[8692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:03 volumio sudo[8692]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:03 volumio sudo[8695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 10 06:14:03 volumio sudo[8695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:03 volumio volumio[8265]: error: Cannot start Volumio Streaming Daemon Feb 10 06:14:03 volumio volumio[8265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 06:14:03 volumio volumio[8265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 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 10 06:14:03 volumio sudo[8695]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:03 volumio volumio[8265]: info: Remote SSH Stopped Feb 10 06:14:03 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:03 volumio volumio[8265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:14:04 volumio nmbd[966]: [2026/02/10 06:14:04.687689, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 10 06:14:04 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 10 06:14:04 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 10 06:14:05 volumio volumio[8265]: info: Setting Geolocation for MyVolumio to as1 Feb 10 06:14:05 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:05 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:05 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:05 volumio volumio[8265]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 10 06:14:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 10 06:14:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:05 volumio go-librespot[8698]: go-librespot daemon starting... Feb 10 06:14:05 volumio go-librespot[8699]: time="2026-02-10T06:14:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:05 volumio go-librespot[8699]: time="2026-02-10T06:14:05+07:00" level=debug msg="app state loaded" Feb 10 06:14:05 volumio go-librespot[8699]: time="2026-02-10T06:14:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:06 volumio volumio[8265]: info: Updating MyVolumio device info Feb 10 06:14:06 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:06 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:06 volumio volumio[8265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+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 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+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 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=info msg="zeroconf server listening on port 37607" Feb 10 06:14:06 volumio volumio[8265]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="obtained new client token: AACf/znRACPkdM/5Q9jkmFTOkwyX0rFBFn9qNbLlR+0Zf7Hofpm2ml2QA+AoJ+mvK8kEPttWiqZyxRPPj6UKmiT1pU4xQBk9x3VoePY6vLP3QnSeyijuK4q42+1FHz9GoxX01Q9w04rXAvc4nnHcTZPeotd2xzfMdHtY8Rgt3+1PeODvAYHpFpoPM86tDYHlIiQKFCPmvCQKpG+tLpW1Q4VUwhuxm/AODEK6A2NS1LPs0AEKh0PK6snrzw==" Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:06 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="new websocket client" Feb 10 06:14:06 volumio volumio[8265]: info: Connection to go-librespot Websocket established Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+07:00" level=debug msg="completed challenge" Feb 10 06:14:06 volumio go-librespot[8699]: time="2026-02-10T06:14:06+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 10 06:14:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:06 volumio volumio[8265]: info: Connection to go-librespot Websocket closed Feb 10 06:14:09 volumio volumio[8265]: info: MYVOLUMIO: Adding device Feb 10 06:14:09 volumio volumio[8265]: info: MYVOLUMIO: Evaluating Server Feb 10 06:14:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 10 06:14:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:10 volumio volumio[8265]: info: Getting Spotify volume Feb 10 06:14:10 volumio volumio[8265]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:10 volumio go-librespot[8738]: go-librespot daemon starting... Feb 10 06:14:10 volumio volumio[8265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:14:10 volumio volumio[8265]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:14:10 volumio volumio[8265]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 10 06:14:10 volumio volumio[8265]: errno: -111, Feb 10 06:14:10 volumio volumio[8265]: code: 'ECONNREFUSED', Feb 10 06:14:10 volumio volumio[8265]: syscall: 'connect', Feb 10 06:14:10 volumio volumio[8265]: address: '127.0.0.1', Feb 10 06:14:10 volumio volumio[8265]: port: 9879, Feb 10 06:14:10 volumio volumio[8265]: response: undefined Feb 10 06:14:10 volumio volumio[8265]: } Feb 10 06:14:10 volumio volumio[8265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=debug msg="app state loaded" Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+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 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+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 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=info msg="zeroconf server listening on port 33003" Feb 10 06:14:10 volumio go-librespot[8739]: time="2026-02-10T06:14:10+07:00" level=debug msg="obtained new client token: AACzPgKOumM1tAjxn8But9didhZmbf1qJKEHNmE+2cBwA/UzCbr8lu+mggvifkYfeDVzTS1uelIOfClNQVoXn2z7jxgG4eCMA0/qIZmayFWfyl4Vqwa92vNS/NysTYBCYQ3XwqieXFawmu4e9z3Bf5Shz7MtWA4MUKRoE6C9GgRex2YjD9wtVI3fiHMC88kt7ZSaSR0Xw1ICVKDcWxNlAOWNqo1rSIS+DdVR3FFA0qFbKCWLwpojHuhTIg==" Feb 10 06:14:11 volumio go-librespot[8739]: time="2026-02-10T06:14:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:11 volumio go-librespot[8739]: time="2026-02-10T06:14:11+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:11 volumio go-librespot[8739]: time="2026-02-10T06:14:11+07:00" level=debug msg="completed challenge" Feb 10 06:14:11 volumio go-librespot[8739]: time="2026-02-10T06:14:11+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 10 06:14:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:11 volumio sudo[8762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 06:13' Feb 10 06:14:11 volumio sudo[8762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:11 volumio sudo[8762]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:12 volumio volumio-remote-updater[643]: [2026-02-10 06:14:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 10 06:14:12 volumio volumio-remote-updater[643]: [2026-02-10 06:14:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 10 06:14:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 10 06:14:12 volumio systemd[1]: volumio.service: Consumed 57.653s CPU time. Feb 10 06:14:12 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 06:14:12 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 06:14:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9826. Feb 10 06:14:12 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 06:14:12 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 10 06:14:12 volumio systemd[1]: volumio.service: Consumed 57.653s CPU time. Feb 10 06:14:12 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 06:14:12 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 06:14:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 10 06:14:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:14 volumio go-librespot[8789]: go-librespot daemon starting... Feb 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+07:00" level=debug msg="app state loaded" Feb 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+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 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+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 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+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 10 06:14:14 volumio go-librespot[8790]: time="2026-02-10T06:14:14+07:00" level=info msg="zeroconf server listening on port 35483" Feb 10 06:14:15 volumio go-librespot[8790]: time="2026-02-10T06:14:15+07:00" level=debug msg="obtained new client token: AAAf5JTmfvdxK7oC5GnuT3yV+LGHSX/NtSknVqViVq8YPdStPnRLZ4TEFeNQv5UK24k3Nk5y02mp9bN8sZ7o6rgtKStUgSvAUix1cDHWnO8JjuePH4j+1h5fvFrmbNHnVCbf8edtRc441wmf82XL2BEBdFEUKNzWRMtID4sOrzkH5XOehqrO65viiBwU0OF02Pe5DegfZSLE+Fq35SJqmrhJWOb73qVFOO3mblD+KQcRrisHzPKkUGw=" Feb 10 06:14:15 volumio go-librespot[8790]: time="2026-02-10T06:14:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:15 volumio go-librespot[8790]: time="2026-02-10T06:14:15+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:15 volumio go-librespot[8790]: time="2026-02-10T06:14:15+07:00" level=debug msg="completed challenge" Feb 10 06:14:15 volumio go-librespot[8790]: time="2026-02-10T06:14:15+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 10 06:14:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:15 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:15 volumio volumio[8774]: info: ----- Volumio3 ---- Feb 10 06:14:15 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:15 volumio volumio[8774]: info: ----- System startup ---- Feb 10 06:14:15 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:17 volumio volumio-remote-updater[643]: [2026-02-10 06:14:17] [connect] Successful connection Feb 10 06:14:17 volumio volumio[8774]: info: MYVOLUMIO Environment detected Feb 10 06:14:17 volumio volumio[8774]: info: Plugin folders cleanup Feb 10 06:14:17 volumio volumio[8774]: info: Scanning into folder /volumio/app/plugins/ Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category audio_interface Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category miscellanea Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category music_service Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category plugins.json Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category system_controller Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category user_interface Feb 10 06:14:17 volumio volumio[8774]: info: Scanning into folder /data/plugins/ Feb 10 06:14:17 volumio volumio[8774]: info: Scanning category music_service Feb 10 06:14:17 volumio volumio[8774]: info: Plugin folders cleanup completed Feb 10 06:14:17 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:17 volumio volumio[8774]: info: ----- Core plugins startup ---- Feb 10 06:14:17 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:17 volumio volumio[8774]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 06:14:17 volumio volumio[8774]: info: Adding plugin upnp to MyMusic Plugins Feb 10 06:14:17 volumio volumio[8774]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 06:14:17 volumio volumio[8774]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 06:14:17 volumio volumio[8774]: info: Loading plugins from folder /data/plugins/ Feb 10 06:14:17 volumio volumio[8774]: info: Loading plugin "system"... Feb 10 06:14:17 volumio volumio[8774]: info: Loading plugin "appearance"... Feb 10 06:14:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 10 06:14:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:18 volumio go-librespot[8810]: go-librespot daemon starting... Feb 10 06:14:18 volumio go-librespot[8811]: time="2026-02-10T06:14:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:18 volumio go-librespot[8811]: time="2026-02-10T06:14:18+07:00" level=debug msg="app state loaded" Feb 10 06:14:18 volumio go-librespot[8811]: time="2026-02-10T06:14:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+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 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+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 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+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 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+07:00" level=info msg="zeroconf server listening on port 41357" Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+07:00" level=debug msg="obtained new client token: AABryznNQ+MCJaNtrwiUUoLjVOlxlZLD7YXiNSlJflI9XD74KiurRWDvtBQ0j9JBZXGl8IKSKzEUSEC9tWpF3hRWG/wmkkbn9efo5ohKbiRxGkoYfMhBoipgmBh0ETn4ntKaMBu6E1LvAM0jOkdFGA8Cz0I/4QgdJjCEg3LyrKNy1qKC49/1MCIlX20zRw568E2OsmXuLuXOrh4tHeZMWKuXb/NFJS6q+DPsGxTIEX2CnWoH4ix1bmJdSQ==" Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "network"... Feb 10 06:14:19 volumio volumio[8774]: info: Refreshing Cached IP Addresses Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:19 volumio sudo[8819]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 06:14:19 volumio sudo[8819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:19 volumio sudo[8819]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:19 volumio sudo[8821]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "services"... Feb 10 06:14:19 volumio sudo[8821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "alsa_controller"... Feb 10 06:14:19 volumio sudo[8821]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:19 volumio sudo[8829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 06:14:19 volumio sudo[8829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+07:00" level=debug msg="completed challenge" Feb 10 06:14:19 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "wizard"... Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "networkfs"... Feb 10 06:14:19 volumio go-librespot[8811]: time="2026-02-10T06:14:19+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 10 06:14:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:19 volumio volumio[8774]: info: Starting Udev Watcher for removable devices Feb 10 06:14:19 volumio volumio[8774]: info: Ignoring mount for partition: boot Feb 10 06:14:19 volumio volumio[8774]: info: Ignoring mount for partition: volumio Feb 10 06:14:19 volumio volumio[8774]: info: Ignoring mount for partition: volumio_data Feb 10 06:14:19 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "volumio_command_line_client"... Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "upnp"... Feb 10 06:14:19 volumio volumio[8774]: info: [1770678859896] Starting Upmpd Daemon Feb 10 06:14:19 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "my_music"... Feb 10 06:14:19 volumio volumio[8774]: info: Loading plugin "mpd"... Feb 10 06:14:20 volumio volumio[8774]: info: Loading plugin "upnp_browser"... Feb 10 06:14:22 volumio sudo[8829]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 10 06:14:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:22 volumio go-librespot[8852]: go-librespot daemon starting... Feb 10 06:14:22 volumio go-librespot[8853]: time="2026-02-10T06:14:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:22 volumio go-librespot[8853]: time="2026-02-10T06:14:22+07:00" level=debug msg="app state loaded" Feb 10 06:14:22 volumio go-librespot[8853]: time="2026-02-10T06:14:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+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 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+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 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+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 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+07:00" level=info msg="zeroconf server listening on port 33873" Feb 10 06:14:23 volumio volumio[8774]: info: Starting UPNP Browser Feb 10 06:14:23 volumio volumio[8774]: info: Loading plugin "alarm-clock"... Feb 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+07:00" level=debug msg="obtained new client token: AABMmf1JpsP6VWyaf0AZwWhIjlGPvURT+lnr5G0ix5Xzzz1VlXLU7wy83zLHV0mgtfN8FSOIGtlMxRhlflRLVnHWcDWP1Qjn1+M3ND3/BhabErHOWKiGEx4gXl2TY4Zt3jdquKcLgHqC7Ca4cQ6HTKqZm1w/yMEyoziuHq7Rr7BO/o/fDJsuoKyRQkqEKBqH/oF9F2269vvlyJ+wb+7bIyqcrnzrxUk8f6T542T4QRdXDwHwsxH7M0B9pg==" Feb 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:23 volumio volumio[8774]: info: Loading plugin "airplay_emulation"... Feb 10 06:14:23 volumio volumio[8774]: info: Starting Shairport Sync Feb 10 06:14:23 volumio volumio[8774]: info: Loading plugin "last_100"... Feb 10 06:14:23 volumio volumio[8774]: info: Loading plugin "webradio"... Feb 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:23 volumio go-librespot[8853]: time="2026-02-10T06:14:23+07:00" level=debug msg="completed challenge" Feb 10 06:14:24 volumio go-librespot[8853]: time="2026-02-10T06:14:24+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 10 06:14:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:24 volumio volumio[8774]: info: Loading plugin "i2s_dacs"... Feb 10 06:14:24 volumio volumio[8774]: info: Loading plugin "volumiodiscovery"... Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** For more information see Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:14:24 volumio volumio[8774]: *** WARNING *** For more information see Feb 10 06:14:24 volumio node[8774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 06:14:24 volumio node[8774]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:14:24 volumio node[8774]: *** WARNING *** For more information see Feb 10 06:14:24 volumio node[8774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 06:14:24 volumio node[8774]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 06:14:24 volumio node[8774]: *** WARNING *** For more information see Feb 10 06:14:24 volumio volumio[8774]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 06:14:24 volumio volumio[8774]: info: Discovery: Started advertising with name: Volumio Feb 10 06:14:24 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 06:14:24 volumio volumio[8774]: info: Loading plugin "spop"... Feb 10 06:14:26 volumio volumio[8774]: info: Loading plugin "ytcr"... Feb 10 06:14:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 10 06:14:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:27 volumio go-librespot[8862]: go-librespot daemon starting... Feb 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+07:00" level=debug msg="app state loaded" Feb 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+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 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+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 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+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 10 06:14:27 volumio go-librespot[8863]: time="2026-02-10T06:14:27+07:00" level=info msg="zeroconf server listening on port 36883" Feb 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+07:00" level=debug msg="obtained new client token: AAB/m44vT3GCpeHdz1XfcYDWqN6QuA0/YBAkPFWjhcQx+5KdNy4lNTfLec+bXndN6c8qX23MjC1yYQfD+wJtl4a38YAY33tiSloPgmdtoQa1qD5w7zhYJLCv8hJ1IXyeLsetVsc8L2iEi7mRmAyyCaBRwTDCgIoEnWWjwEVsbFL1fXJSYmR4NeQ47ZFRkcn0Q79aSgjCNTLloHjUufAbnk524N7nrk7Hi1ivs3gXWfj3G+oWHHzC+r8=" Feb 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+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 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+07:00" level=debug msg="completed challenge" Feb 10 06:14:28 volumio go-librespot[8863]: time="2026-02-10T06:14:28+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 10 06:14:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:28 volumio volumio[8774]: info: Loading plugin "ytmusic"... Feb 10 06:14:29 volumio volumio-remote-updater[643]: [2026-02-10 06:14:29] [connect] Successful connection Feb 10 06:14:29 volumio volumio[8774]: info: Loading plugin "outputs"... Feb 10 06:14:29 volumio volumio[8774]: info: Loading plugin "albumart"... Feb 10 06:14:29 volumio volumio[8774]: info: Plugin example_plugin is not enabled Feb 10 06:14:29 volumio volumio[8774]: info: Loading plugin "inputs"... Feb 10 06:14:29 volumio volumio[8774]: info: Loading plugin "updater_comm"... Feb 10 06:14:30 volumio volumio[8774]: info: Plugin mpdemulation is not enabled Feb 10 06:14:30 volumio volumio[8774]: info: Loading plugin "rest_api"... Feb 10 06:14:30 volumio volumio[8774]: info: Loading plugin "websocket"... Feb 10 06:14:30 volumio volumio[8774]: info: Starting Socket.io Server version 1.7.4 Feb 10 06:14:30 volumio volumio[8774]: info: Loading plugin "RoonBridge"... Feb 10 06:14:30 volumio volumio[8774]: info: Applying required configuration parameters for plugin RoonBridge Feb 10 06:14:30 volumio volumio[8774]: info: Loading i18n strings for locale en Feb 10 06:14:30 volumio volumio[8774]: Updating browse sources language Feb 10 06:14:30 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:30 volumio volumio[8885]: Forking 3 albumart workers Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::initPlayerControls Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: Express server listening on port 3000 Feb 10 06:14:31 volumio volumio[8774]: [Metrics] WebUI: 16s 193.85ms Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::resetVolumioState Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::getcurrentVolume Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:14:31 volumio sudo[8931]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 06:14:31 volumio sudo[8931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:31 volumio sudo[8931]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:31 volumio volumio[8774]: info: Volumio Network Manager: Network status updated: 1 Feb 10 06:14:31 volumio sudo[8933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 06:14:31 volumio sudo[8933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:31 volumio sudo[8933]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:31 volumio volumio[8774]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::pushState Feb 10 06:14:31 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::volumioPushState Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::updateTrackBlock Feb 10 06:14:31 volumio volumio[8774]: info: CorePlayQueue::getTrackBlock Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:14:31 volumio volumio-remote-updater[643]: [2026-02-10 06:14:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770678869 101 Feb 10 06:14:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 10 06:14:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:31 volumio volumio[8774]: 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 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:31 volumio volumio[8774]: info: Reloading queue from file Feb 10 06:14:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::setRepeat null single undefined Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::pushState Feb 10 06:14:31 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:31 volumio go-librespot[8940]: go-librespot daemon starting... Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::volumioPushState Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::setRandom null Feb 10 06:14:31 volumio volumio[8774]: info: CoreStateMachine::pushState Feb 10 06:14:31 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:31 volumio volumio[8774]: info: CoreCommandRouter::volumioPushState Feb 10 06:14:32 volumio volumio[8774]: info: Setting Device type: Raspberry PI Feb 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+07:00" level=debug msg="app state loaded" Feb 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:32 volumio volumio[8774]: info: Completed loading Core Plugins Feb 10 06:14:32 volumio volumio[8774]: info: Preparing to generate the ALSA configuration file Feb 10 06:14:32 volumio volumio[8774]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:14:32 volumio volumio[8774]: info: CoreStateMachine::pushState Feb 10 06:14:32 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::volumioPushState Feb 10 06:14:32 volumio volumio[8774]: info: Asound.conf file unchanged, so no further update is needed Feb 10 06:14:32 volumio volumio[8774]: info: Output device has changed, restarting MPD Feb 10 06:14:32 volumio sudo[8958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 06:14:32 volumio volumio[8774]: info: Output device has changed, restarting Shairport Sync Feb 10 06:14:32 volumio sudo[8958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:32 volumio sudo[8956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 06:14:32 volumio sudo[8956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:32 volumio sudo[8958]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:32 volumio volumio[8774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:14:32 volumio volumio[8774]: info: ___________ START PLUGINS ___________ Feb 10 06:14:32 volumio sudo[8960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 06:14:32 volumio sudo[8960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:32 volumio volumio[8774]: info: ControllerMpd::onStart: Initializing MPD Feb 10 06:14:32 volumio volumio[8774]: info: Creating MPD Configuration file Feb 10 06:14:32 volumio sudo[8956]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:14:32 volumio volumio[8774]: info: [1770678872513] CoreMusicLibrary::Adding element Media Servers Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:32 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 06:14:32 volumio sudo[8969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 06:14:32 volumio sudo[8969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:32 volumio volumio[8774]: info: UPNP Browser: Client initialized successfully Feb 10 06:14:32 volumio sudo[8969]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+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 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+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 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+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 10 06:14:32 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 06:14:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 06:14:32 volumio systemd[1]: mpd.service: Consumed 7.227s CPU time. Feb 10 06:14:32 volumio go-librespot[8946]: time="2026-02-10T06:14:32+07:00" level=info msg="zeroconf server listening on port 41475" Feb 10 06:14:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 06:14:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 06:14:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 06:14:32 volumio sudo[8971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 06:14:32 volumio volumio[8774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:32 volumio sudo[8971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 06:14:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 06:14:32 volumio volumio[8774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:14:32 volumio volumio[8774]: info: [1770678872853] CoreMusicLibrary::Adding element Last_100 Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:14:32 volumio volumio[8774]: info: [1770678872862] CoreMusicLibrary::Adding element Webradio Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:32 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:14:32 volumio volumio[8774]: info: Initializing BBC Radios Feb 10 06:14:32 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 06:14:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 06:14:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 06:14:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 06:14:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 06:14:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 06:14:33 volumio go-librespot[8946]: time="2026-02-10T06:14:33+07:00" level=debug msg="obtained new client token: AAAk3x6+hXFbta6G8QT2Vj4tO6jQq4+n/6Mu/c0kAVmLWZwvHYyvPc5eYkj3ey8siJX33C0UIkt+cJWbvFbKaYEwNqfT2ExVHy+A9BAFHEcB0PxYX8P9Kn/mazaFUXUB8ASP6na/ryjQ+QX2b/WXYdnUw2NFpVFqc+sttWsb1qQ55UbtSqXBvPUrlJzEzpjOpd60qP6u2xkwc9XUucripw9TV4BFThsXuztxhYDum9yqlCGB0Tkw9YBCjQ==" Feb 10 06:14:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 06:14:33 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 06:14:33 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:33 volumio volumio[8774]: info: Creating Spotify config file Feb 10 06:14:33 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:33 volumio go-librespot[8946]: time="2026-02-10T06:14:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:33 volumio go-librespot[8946]: time="2026-02-10T06:14:33+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:33 volumio go-librespot[8946]: time="2026-02-10T06:14:33+07:00" level=debug msg="completed challenge" Feb 10 06:14:33 volumio go-librespot[8946]: time="2026-02-10T06:14:33+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 10 06:14:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:33 volumio sudo[8993]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 06:14:33 volumio sudo[8993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 06:14:33 volumio sudo[8993]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:34 volumio volumio[8898]: Starting albumart workers Feb 10 06:14:34 volumio volumio[8774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:14:34 volumio volumio[8774]: info: [1770678874476] CoreMusicLibrary::Adding element YouTube Music Feb 10 06:14:34 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:34 volumio volumio[8774]: Cannot find translation for source YouTube Music Feb 10 06:14:34 volumio volumio[8774]: info: Volumio Calling Home Feb 10 06:14:34 volumio volumio[8897]: Starting albumart workers Feb 10 06:14:34 volumio sudo[9003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 10 06:14:34 volumio sudo[9003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:34 volumio sudo[9003]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:34 volumio volumio[8899]: Starting albumart workers Feb 10 06:14:35 volumio volumio[8774]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 06:14:35 volumio volumio[8774]: info: Discovery: Found device Volumio Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:35 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:35 volumio volumio[8774]: info: MPD Permissions set Feb 10 06:14:35 volumio volumio[8774]: info: MPD Permissions set Feb 10 06:14:35 volumio volumio[8774]: info: Upmpdcli Daemon Started Feb 10 06:14:35 volumio volumio[8774]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 06:14:35 volumio volumio[8774]: info: Discovery: Found device Volumio Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:35 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:35 volumio volumio[8774]: info: Volumio called home Feb 10 06:14:35 volumio volumio[8774]: info: Spotify config file written Feb 10 06:14:35 volumio sudo[9008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 06:14:35 volumio sudo[9008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:35 volumio volumio[8774]: 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 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:35 volumio go-librespot[9010]: go-librespot daemon starting... Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:35 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio sudo[9008]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+07:00" level=debug msg="app state loaded" Feb 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 06:14:36 volumio volumio[8774]: info: No need to fix Spotify hosts Feb 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+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 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+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 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+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 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+07:00" level=info msg="zeroconf server listening on port 34213" Feb 10 06:14:36 volumio go-librespot[9016]: time="2026-02-10T06:14:36+07:00" level=debug msg="obtained new client token: AABZ1giDGbQj/h+tYMdrT8gTqkzpIhtMgffDWhWbKNj1ya7Wf2K52hNCmUTYSQtd2sYIBkVbAQ/HTjDY9MvSzYupJGAQmX+2X+roKAjGCd62IPuufwuJmZHoeEswLOUu8P64bzLmhdtvwhf4iG5OyjbSEcdxqbE7pST4tK4+trbeKi1jJPmHAVbatMHpNwFgi6HSiqU8hjMsss1A2qpWyUQ9VCcDv+sYb3Tkf+sBQMSdqWpfyeBEFkPlhg==" Feb 10 06:14:37 volumio go-librespot[9016]: time="2026-02-10T06:14:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:37 volumio go-librespot[9016]: time="2026-02-10T06:14:37+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:37 volumio go-librespot[9016]: time="2026-02-10T06:14:37+07:00" level=debug msg="completed challenge" Feb 10 06:14:37 volumio go-librespot[9016]: time="2026-02-10T06:14:37+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 10 06:14:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:37 volumio volumio[8774]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 06:14:37 volumio volumio[8774]: SPOTIFY: BQB60KWHnC2hOoUDZtD-07AbDV8RUNYV2bmyYaT4X8OUBLalBVd6grVGcVEvwbsErZDiy4H5lhHAzmtgziPEdyp5nGPdSWH5lzKyGEYg0hauwR5p4twrwXxN8THERXJFSTkWKK8O1WujfS66-BC4HbAkSXjSF8cElA2haRBgoy6JAoJBqmc90NEALXqU4EfZvWhIj1ItL6hvRG6eSZv-er3k9voYJSznr4kOvQk6IfaNI5G5j8aYzFGv38fD1oJnjAOZgWu7HCdaDZcTfBd8EnQTZRDDiKxxexsP008ufz6GB-pWuJnS-khd Feb 10 06:14:37 volumio volumio[8774]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 06:14:37 volumio volumio[8774]: info: New Spotify access token = BQB60KWHnC2hOoUDZtD-07AbDV8RUNYV2bmyYaT4X8OUBLalBVd6grVGcVEvwbsErZDiy4H5lhHAzmtgziPEdyp5nGPdSWH5lzKyGEYg0hauwR5p4twrwXxN8THERXJFSTkWKK8O1WujfS66-BC4HbAkSXjSF8cElA2haRBgoy6JAoJBqmc90NEALXqU4EfZvWhIj1ItL6hvRG6eSZv-er3k9voYJSznr4kOvQk6IfaNI5G5j8aYzFGv38fD1oJnjAOZgWu7HCdaDZcTfBd8EnQTZRDDiKxxexsP008ufz6GB-pWuJnS-khd Feb 10 06:14:37 volumio volumio[8774]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 06:14:37 volumio volumio[8774]: info: Starting Shairport Sync Feb 10 06:14:37 volumio volumio[8774]: info: Starting Shairport Sync Feb 10 06:14:37 volumio volumio[8774]: info: Starting Shairport Sync Feb 10 06:14:37 volumio sudo[9048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:14:37 volumio sudo[9048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:37 volumio sudo[9050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:14:37 volumio sudo[9050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:37 volumio sudo[9052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 06:14:37 volumio sudo[9052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 06:14:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 06:14:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:14:37 volumio systemd[1]: shairport-sync.service: Consumed 2.373s CPU time. Feb 10 06:14:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 06:14:37 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:37 volumio sudo[9050]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:37 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:37 volumio sudo[9048]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:37 volumio sudo[9052]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:37 volumio volumio[8774]: info: Shairport-Sync Started Feb 10 06:14:37 volumio volumio[8774]: Error adding Membership: Error: addMembership EINVAL Feb 10 06:14:37 volumio volumio[8774]: info: Shairport-Sync Started Feb 10 06:14:38 volumio volumio[8774]: info: Shairport-Sync Started Feb 10 06:14:38 volumio volumio[8774]: 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 10 06:14:38 volumio volumio[8774]: info: Spotify Successfully logged in Feb 10 06:14:38 volumio volumio[8774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 06:14:38 volumio volumio[8774]: info: [1770678878138] CoreMusicLibrary::Adding element Spotify Feb 10 06:14:38 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 06:14:38 volumio volumio[8774]: Cannot find translation for source YouTube Music Feb 10 06:14:38 volumio volumio[8774]: Cannot find translation for source Spotify Feb 10 06:14:38 volumio volumio[8774]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 06:14:38 volumio volumio[8774]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 06:14:38 volumio volumio[8774]: info: VolumeController:: Volume=100 Mute =false Feb 10 06:14:38 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:38 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:38 volumio volumio[8774]: info: CoreStateMachine::pushState Feb 10 06:14:38 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:38 volumio volumio[8774]: info: CoreCommandRouter::volumioPushState Feb 10 06:14:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 10 06:14:40 volumio volumio[8774]: info: go-librespot daemon successfully initialized Feb 10 06:14:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:40 volumio mpd[9000]: 2026-02-10T06:14:40 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 06:14:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:40 volumio go-librespot[9088]: go-librespot daemon starting... Feb 10 06:14:40 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 06:14:40 volumio sudo[8960]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:40 volumio sudo[8971]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=debug msg="app state loaded" Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:40 volumio volumio[8774]: error: MPD error: The expression evaluated to a falsy value: Feb 10 06:14:40 volumio volumio[8774]: assert.ok(self.idling) Feb 10 06:14:40 volumio volumio[8774]: error: The expression evaluated to a falsy value: Feb 10 06:14:40 volumio volumio[8774]: assert.ok(self.idling) Feb 10 06:14:40 volumio volumio[8774]: error: updateQueue error: null Feb 10 06:14:40 volumio volumio[8774]: info: Completed starting Core Plugins Feb 10 06:14:40 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:40 volumio volumio[8774]: info: ----- MyVolumio plugins startup ---- Feb 10 06:14:40 volumio volumio[8774]: info: ------------------------------------------- Feb 10 06:14:40 volumio volumio[8774]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 06:14:40 volumio volumio[8774]: info: MPD running with PID9000 Feb 10 06:14:40 volumio volumio[8774]: ,establishing connection Feb 10 06:14:40 volumio volumio[8774]: error: updateQueue error: null Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 06:14:40 volumio go-librespot[9091]: time="2026-02-10T06:14:40+07:00" level=info msg="zeroconf server listening on port 35187" Feb 10 06:14:41 volumio go-librespot[9091]: time="2026-02-10T06:14:41+07:00" level=debug msg="obtained new client token: AAB9NsMW7oV2P5s9tyeGamtqsZ8w2eqJpkm8uMSYMyFpcsJxeTh8pdxD1cHhsWwT73cnFvh+70WWqbqohdGglBxUrl6etkRjTWnJ5Lo6iVFAGCsyPIjbVoyPtz41DKm50BFvXtsc6uuLLm63dec01Aw4YomBMTdtw93LW2lvidABm5jlI5kRTRTPIsGYBE7ic0jY2aZh6vUi6ulzklh1WMpjoNnogohXNk3AYMwNiXuzAMP1zve4xPM=" Feb 10 06:14:41 volumio go-librespot[9091]: time="2026-02-10T06:14:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:41 volumio go-librespot[9091]: time="2026-02-10T06:14:41+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:41 volumio go-librespot[9091]: time="2026-02-10T06:14:41+07:00" level=debug msg="completed challenge" Feb 10 06:14:41 volumio go-librespot[9091]: time="2026-02-10T06:14:41+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 10 06:14:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:43 volumio volumio[8774]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:43 volumio volumio[8774]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:14:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 10 06:14:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:44 volumio go-librespot[9103]: go-librespot daemon starting... Feb 10 06:14:44 volumio go-librespot[9104]: time="2026-02-10T06:14:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:44 volumio go-librespot[9104]: time="2026-02-10T06:14:44+07:00" level=debug msg="app state loaded" Feb 10 06:14:44 volumio go-librespot[9104]: time="2026-02-10T06:14:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+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 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+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 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+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 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=info msg="zeroconf server listening on port 44669" Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=debug msg="obtained new client token: AADy3ail04uCPovM4GGxgeKGDq28WIiDBI23Aktjeo1sWYCCyfnIb5pAIGqCUJutwkEH7UhdfszFtk9wprOnpgKSTIWQct9wMrWmIIuh95LC5idysPyF0Afbl+YSWp9OAmjg/CT+1FWj81NiPxWC+rfZmm88h0uoBqcfFyI0EBIo6yCqKn7MEEfzw2vJqhkMr7djOEaufnFrJKmEt3YJ+Q3RY/hVEcIMW5eiwvsR5XtE2Tpoq+vw9B6tTA==" Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:45 volumio volumio[8774]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=debug msg="completed challenge" Feb 10 06:14:45 volumio go-librespot[9104]: time="2026-02-10T06:14:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 06:14:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:46 volumio volumio[8774]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:46 volumio volumio[8774]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 06:14:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 10 06:14:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:48 volumio go-librespot[9111]: go-librespot daemon starting... Feb 10 06:14:48 volumio go-librespot[9112]: time="2026-02-10T06:14:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:48 volumio go-librespot[9112]: time="2026-02-10T06:14:48+07:00" level=debug msg="app state loaded" Feb 10 06:14:48 volumio go-librespot[9112]: time="2026-02-10T06:14:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:49 volumio volumio[8774]: info: Initializing connection to go-librespot Websocket Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="new websocket client" Feb 10 06:14:49 volumio volumio[8774]: info: Connection to go-librespot Websocket established Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+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 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+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 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=info msg="zeroconf server listening on port 36023" Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 06:14:49 volumio volumio[8774]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="obtained new client token: AACewfVClbets8wP2TCc10R/ARL8bYbhYsGWNB9nxPE1H25uUd6KwyiNIaJ/3sKM0kt3MhrCmJert7QY+QzqqAdvrVgu8dZWuoa95oaXdNxg91VCAulekIA359TADa/ViM9tlwEdIm18f77rq8e8CjZQVsM0Lo/v5oTVLrV5OkSteEl/2n6xiDyDAcobYwDXRIZc1IdbJj1dRNJsoIMvenXKfsElZ6IgqvcSxWOdvqZNe8x0n3lqSHO7lQ==" Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:49 volumio go-librespot[9112]: time="2026-02-10T06:14:49+07:00" level=debug msg="completed challenge" Feb 10 06:14:50 volumio go-librespot[9112]: time="2026-02-10T06:14:50+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 06:14:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:51 volumio volumio[8774]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 06:14:51 volumio volumio[8774]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 06:14:51 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:51 volumio volumio[8774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 06:14:51 volumio volumio[8774]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 06:14:51 volumio volumio[8774]: info: MyVolumio login type: Token Feb 10 06:14:51 volumio volumio[8774]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 06:14:51 volumio volumio[8774]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 06:14:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 10 06:14:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 06:14:53 volumio go-librespot[9135]: go-librespot daemon starting... Feb 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=debug msg="app state loaded" Feb 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 06:14:53 volumio volumio[8774]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 06:14:53 volumio volumio[8774]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 06:14:53 volumio volumio[8774]: info: Streaming services startup Feb 10 06:14:53 volumio volumio[8774]: info: Starting Streaming Daemon Feb 10 06:14:53 volumio sudo[9143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 06:14:53 volumio sudo[9143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 06:14:53 volumio volumio[8774]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 06:14:53 volumio sudo[9143]: pam_unix(sudo:session): session closed for user root Feb 10 06:14:53 volumio volumio[8774]: info: Getting Spotify volume Feb 10 06:14:53 volumio volumio[8774]: info: Connection to go-librespot Websocket closed Feb 10 06:14:53 volumio volumio[8774]: error: Cannot start Volumio Streaming Daemon Feb 10 06:14:53 volumio volumio[8774]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 06:14:53 volumio volumio[8774]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 06:14:53 volumio volumio[8774]: 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 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+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 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+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 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=info msg="zeroconf server listening on port 33571" Feb 10 06:14:53 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:53 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:53 volumio volumio[8774]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 06:14:53 volumio volumio[8774]: SPOTIFY: SPOTIFY VOLUME undefined Feb 10 06:14:53 volumio volumio[8774]: SPOTIFY: VOLUMIO VOLUME 100 Feb 10 06:14:53 volumio volumio[8774]: info: Aligning Spotify Volume to Volumio Volume Feb 10 06:14:53 volumio volumio[8774]: info: CoreCommandRouter::volumioGetState Feb 10 06:14:53 volumio volumio[8774]: info: CorePlayQueue::getTrack 0 Feb 10 06:14:53 volumio volumio[8774]: info: Setting Spotify Volume from Volumio: 100 Feb 10 06:14:53 volumio go-librespot[9136]: time="2026-02-10T06:14:53+07:00" level=debug msg="obtained new client token: AAAelftr4Q4DH37mox5NH+IBPryuPsFaHjPvF/iGqL+UGjWKvoro9rwOQ2S5aK7a7Z/Uk+3jWBB49Rh76bq2mZxtwIE7Hq4b1KJp2pAWIZtoGyjk4Uc02c9ibSBOLmI8mZ/M9lacaHZ8LRfpaNOKObB9DrkKZdRPY3b4yTqFpkZH8JqGm2MqIdiCMA7WyK+ocW3MLY5wbemictHanv+PzF+9lRDbhbhtIjgzjajIP0pY8klyRRlBo2KVJg==" Feb 10 06:14:54 volumio go-librespot[9136]: time="2026-02-10T06:14:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 06:14:54 volumio go-librespot[9136]: time="2026-02-10T06:14:54+07:00" level=debug msg="completed keyexchange" Feb 10 06:14:54 volumio go-librespot[9136]: time="2026-02-10T06:14:54+07:00" level=debug msg="completed challenge" Feb 10 06:14:54 volumio go-librespot[9136]: time="2026-02-10T06:14:54+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 10 06:14:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 06:14:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 06:14:54 volumio volumio[8774]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:14:54 volumio volumio[8774]: Error: socket hang up Feb 10 06:14:54 volumio volumio[8774]: at connResetException (node:internal/errors:720:14) Feb 10 06:14:54 volumio volumio[8774]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 10 06:14:54 volumio volumio[8774]: at Socket.emit (node:events:526:35) Feb 10 06:14:54 volumio volumio[8774]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 10 06:14:54 volumio volumio[8774]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 10 06:14:54 volumio volumio[8774]: code: 'ECONNRESET', Feb 10 06:14:54 volumio volumio[8774]: response: undefined Feb 10 06:14:54 volumio volumio[8774]: } Feb 10 06:14:54 volumio volumio[8774]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 06:14:55 volumio sudo[9164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 06:13' Feb 10 06:14:55 volumio sudo[9164]: 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"