Aug 26 00:44:00 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:01 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Aug 26 00:44:01 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 00:44:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 00:44:01 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:02 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:03 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:05 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:05 volumio mpd[1598]: 2026-08-26T00:44:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 00:44:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 00:44:05 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:05 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:05 volumio volumio[1385]: info: Discovery: adding 97f44699-c849-49ee-81cb-0b69e6cf50df Aug 26 00:44:05 volumio volumio[1385]: info: Discovery: Found device Volumio Aug 26 00:44:05 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Aug 26 00:44:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:05 volumio volumio[1385]: info: Discovery: this is already registered, 97f44699-c849-49ee-81cb-0b69e6cf50df Aug 26 00:44:05 volumio volumio[1385]: info: Discovery: Found device Volumio Aug 26 00:44:05 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Aug 26 00:44:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:06 volumio volumio[1385]: info: Completed starting Core Plugins Aug 26 00:44:06 volumio volumio[1385]: info: ------------------------------------------- Aug 26 00:44:06 volumio volumio[1385]: info: ----- MyVolumio plugins startup ---- Aug 26 00:44:06 volumio volumio[1385]: info: ------------------------------------------- Aug 26 00:44:06 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:06 volumio volumio[1385]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 00:44:06 volumio volumio[1385]: info: MPD Permissions set Aug 26 00:44:06 volumio volumio[1385]: info: MPD Permissions set Aug 26 00:44:06 volumio volumio[1385]: info: Upmpdcli Daemon Started Aug 26 00:44:06 volumio volumio[1385]: info: VolumeController:: Volume=30 Mute =false Aug 26 00:44:06 volumio volumio[1385]: info: CoreStateMachine::pushState Aug 26 00:44:06 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:06 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Aug 26 00:44:07 volumio volumio[1385]: info: MPD running with PID1598 Aug 26 00:44:07 volumio volumio[1385]: ,establishing connection Aug 26 00:44:07 volumio volumio[1385]: info: Spotify config file written Aug 26 00:44:07 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:07 volumio sudo[1665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 00:44:07 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:44:07 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:44:07 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:44:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:07 volumio go-librespot[1667]: go-librespot daemon starting... Aug 26 00:44:07 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:08 volumio volumio[1540]: Starting albumart workers Aug 26 00:44:09 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:09 volumio go-librespot[1668]: time="2026-08-26T00:44:09+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:09 volumio go-librespot[1668]: time="2026-08-26T00:44:09+10:00" level=debug msg="app state loaded" Aug 26 00:44:09 volumio go-librespot[1668]: time="2026-08-26T00:44:09+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10: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]" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10: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]" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10: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]" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10:00" level=info msg="zeroconf server listening on port 36675" Aug 26 00:44:10 volumio volumio[1546]: Starting albumart workers Aug 26 00:44:10 volumio volumio[1547]: Starting albumart workers Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10:00" level=debug msg="obtained new client token: AAFnl13oB6hXqu68Ay+fVQ1PG0qTHkui8eLmeD3tKSN0Cf+Syw2MOLt+Vt+mSEuD8fSrTdrIUvjHQd7YdAWwb1TgHY+ilWCtZrljepumigOOtnQafH5HDawettgrBt8cW9l28mQnELn9F0Jn/VrQwjZalj6amGV7fpY9WzAxrdwMnYsemYsujuTgUvTQ5NvNbeu/bLzjKtMK7BlA/ommNFUKWpfP/0JOxMjshro0dW9uRkhQud+54Sr3ng==" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:10 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:10 volumio go-librespot[1668]: time="2026-08-26T00:44:10+10:00" level=debug msg="completed challenge" Aug 26 00:44:11 volumio go-librespot[1668]: time="2026-08-26T00:44:11+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:11 volumio go-librespot[1668]: time="2026-08-26T00:44:11+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:11 volumio volumio[1385]: error: MPD error: The expression evaluated to a falsy value: Aug 26 00:44:11 volumio volumio[1385]: assert.ok(self.idling) Aug 26 00:44:11 volumio volumio[1385]: error: The expression evaluated to a falsy value: Aug 26 00:44:11 volumio volumio[1385]: assert.ok(self.idling) Aug 26 00:44:11 volumio volumio[1385]: error: MPD error: The expression evaluated to a falsy value: Aug 26 00:44:11 volumio volumio[1385]: assert.ok(self.idling) Aug 26 00:44:11 volumio volumio[1385]: error: The expression evaluated to a falsy value: Aug 26 00:44:11 volumio volumio[1385]: assert.ok(self.idling) Aug 26 00:44:12 volumio volumio[1385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 26 00:44:12 volumio volumio[1385]: info: Volumio called home Aug 26 00:44:12 volumio volumio[1385]: info: No need to fix Spotify hosts Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:13 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:44:13 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:13 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 26 00:44:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:14 volumio go-librespot[1702]: go-librespot daemon starting... Aug 26 00:44:14 volumio go-librespot[1703]: time="2026-08-26T00:44:14+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:14 volumio go-librespot[1703]: time="2026-08-26T00:44:14+10:00" level=debug msg="app state loaded" Aug 26 00:44:14 volumio go-librespot[1703]: time="2026-08-26T00:44:14+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:14 volumio volumio[1385]: error: updateQueue error: null Aug 26 00:44:15 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:15 volumio volumio[1385]: info: New Spotify access tokenBQBRW64a5F... Aug 26 00:44:15 volumio volumio[1385]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 26 00:44:15 volumio go-librespot[1703]: time="2026-08-26T00:44:15+10: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]" Aug 26 00:44:15 volumio go-librespot[1703]: time="2026-08-26T00:44:15+10: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]" Aug 26 00:44:15 volumio go-librespot[1703]: time="2026-08-26T00:44:15+10: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]" Aug 26 00:44:15 volumio go-librespot[1703]: time="2026-08-26T00:44:15+10:00" level=info msg="zeroconf server listening on port 36607" Aug 26 00:44:15 volumio go-librespot[1703]: time="2026-08-26T00:44:15+10:00" level=debug msg="obtained new client token: AAGKOSXQZ8p/kdzSgh0Xhd7L4/PPVaBu9R4f9RgFFRlcBpgGu9qH7mZtke3wayH0sM2l3S/duAJbJrsYTIkehPtGb9n7VOKtXvI2A7coZfICYP0nEvCiBA4gqsFLUWf4goN2go25YJdtq4QxKpzimafEMosi78muGlpri1ktXoqZDZV9IO2/WGnKqk52NVAHFjT7xQ+qJ3Auul/KudvN4DEvy0lm9HHRNDnMjL/MzsnI8xKt9kvEuyNCYw==" Aug 26 00:44:16 volumio volumio[1385]: info: Starting Shairport Sync Aug 26 00:44:16 volumio go-librespot[1703]: time="2026-08-26T00:44:16+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:16 volumio volumio[1385]: info: Starting Shairport Sync Aug 26 00:44:16 volumio volumio[1385]: info: Starting Shairport Sync Aug 26 00:44:16 volumio go-librespot[1703]: time="2026-08-26T00:44:16+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:16 volumio go-librespot[1703]: time="2026-08-26T00:44:16+10:00" level=debug msg="completed challenge" Aug 26 00:44:16 volumio go-librespot[1703]: time="2026-08-26T00:44:16+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:16 volumio sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 00:44:16 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:44:16 volumio sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 00:44:16 volumio volumio[1385]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 26 00:44:16 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:44:16 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 00:44:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 00:44:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:16 volumio systemd[1]: shairport-sync.service: Consumed 2.621s CPU time. Aug 26 00:44:16 volumio sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 00:44:16 volumio go-librespot[1703]: time="2026-08-26T00:44:16+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:16 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:44:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 00:44:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 00:44:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:17 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:17 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 00:44:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 00:44:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 00:44:17 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:17 volumio volumio[1385]: info: Shairport-Sync Started Aug 26 00:44:17 volumio volumio[1385]: Error adding Membership: Error: addMembership EINVAL Aug 26 00:44:17 volumio volumio[1385]: info: go-librespot daemon successfully initialized Aug 26 00:44:18 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:18 volumio volumio[1385]: info: Shairport-Sync Started Aug 26 00:44:18 volumio volumio[1385]: info: Shairport-Sync Started Aug 26 00:44:19 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Aug 26 00:44:19 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:19 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 26 00:44:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:20 volumio go-librespot[1751]: go-librespot daemon starting... Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10:00" level=debug msg="app state loaded" Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin bluetooth to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin multiroom to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin metavolumio to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin cd_controller to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 26 00:44:20 volumio volumio[1385]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10: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]" Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10: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]" Aug 26 00:44:20 volumio go-librespot[1753]: time="2026-08-26T00:44:20+10: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]" Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=info msg="zeroconf server listening on port 35513" Aug 26 00:44:21 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=debug msg="obtained new client token: AAFXBseCe7qyBLncchxdw3eoba75P5JoZYuU6/Jywvhl2WiCXRgVfPelMPGzSnGdkHWv1onu9giVeGL8MDGxyWwrZYT0y0Our3h54gFMFksaSK07pAKpC6Htw5RWLmWQy6LAq8O4aF5BF+4b71nuaYPqu61yUKbbZBYoe2aYN+OuUr3SLWH/I8lhAs5bzJ1zqzFwxFBE8mFQ8lG5PfPqkc5qciTtP2HkhbJFUtEzrC7jHU+KZ6iSLbk=" Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=debug msg="completed challenge" Aug 26 00:44:21 volumio go-librespot[1753]: time="2026-08-26T00:44:21+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:22 volumio go-librespot[1753]: time="2026-08-26T00:44:22+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:22 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:24 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:25 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 26 00:44:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:25 volumio go-librespot[1780]: go-librespot daemon starting... Aug 26 00:44:25 volumio go-librespot[1781]: time="2026-08-26T00:44:25+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:25 volumio go-librespot[1781]: time="2026-08-26T00:44:25+10:00" level=debug msg="app state loaded" Aug 26 00:44:25 volumio go-librespot[1781]: time="2026-08-26T00:44:25+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10: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]" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10: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]" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10: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]" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10:00" level=info msg="zeroconf server listening on port 39323" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10:00" level=debug msg="obtained new client token: AAEcx3q+KoF/wAMwpMh2OblhdDnMiwxrhSZJlnmUSanUEflK28cUwkqlyFn4vO3qo0HgF1ERRf1ExdZfxDpAf0fRLkVBbFi1+JvGSf/YJoGZi2MlnyJMzYHRSN2iI2IZEQo3Q+pjMAPniAOOE1Wevnw+jwzFLpltCLSxSE9GGbEPwV9CgXGLiYKuk1CmxZL3cJuzseN2zh6oP+vU+nzx8Jq1bt6rcY6JGX3Esk5dpYyswKy/OEA2Kf3MoA==" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:26 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:26 volumio go-librespot[1781]: time="2026-08-26T00:44:26+10:00" level=debug msg="completed challenge" Aug 26 00:44:27 volumio go-librespot[1781]: time="2026-08-26T00:44:27+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:27 volumio go-librespot[1781]: time="2026-08-26T00:44:27+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:28 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 26 00:44:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:30 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:30 volumio go-librespot[1791]: go-librespot daemon starting... Aug 26 00:44:30 volumio go-librespot[1792]: time="2026-08-26T00:44:30+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:30 volumio go-librespot[1792]: time="2026-08-26T00:44:30+10:00" level=debug msg="app state loaded" Aug 26 00:44:30 volumio go-librespot[1792]: time="2026-08-26T00:44:30+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10: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]" Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10: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]" Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10: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]" Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10:00" level=info msg="zeroconf server listening on port 42891" Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10:00" level=debug msg="obtained new client token: AAEs1KVsZ/iKZjjUeIwVp1SK5aawKsTH6Nwj2klXSDyCh/yQRpxl0hHCGM0CVd73OAz4j3YH3yqbmy9g/BUsEWKsx8mH9zWJV1ehciu+FOXIhr0RoLMEE3wO23+FHdx+PhxlcOJ0NgIsOEIkImTSOsnf+wFuPIyAHFmkIS/UoOQbu57MHzmJcjc3sjxVIe7Sr68x82pgOzaGTGd2Ie4PxheBqHY+JAxqAr9ctPZsCrsiFRIPO4WDKKH+9g==" Aug 26 00:44:31 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:31 volumio go-librespot[1792]: time="2026-08-26T00:44:31+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:32 volumio go-librespot[1792]: time="2026-08-26T00:44:32+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:32 volumio go-librespot[1792]: time="2026-08-26T00:44:32+10:00" level=debug msg="completed challenge" Aug 26 00:44:32 volumio go-librespot[1792]: time="2026-08-26T00:44:32+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:32 volumio go-librespot[1792]: time="2026-08-26T00:44:32+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:32 volumio volumio[1385]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 00:44:32 volumio volumio[1385]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 00:44:32 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:44:32 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:44:32 volumio volumio[1385]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 00:44:33 volumio volumio[1385]: info: MyVolumio login type: Token Aug 26 00:44:33 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:33 volumio volumio[1385]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 00:44:33 volumio volumio[1385]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 26 00:44:34 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 26 00:44:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:35 volumio go-librespot[1815]: go-librespot daemon starting... Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:35+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10:00" level=debug msg="app state loaded" Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:36 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10: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]" Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10: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]" Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10: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]" Aug 26 00:44:36 volumio go-librespot[1816]: time="2026-08-26T00:44:36+10:00" level=info msg="zeroconf server listening on port 35529" Aug 26 00:44:37 volumio go-librespot[1816]: time="2026-08-26T00:44:37+10:00" level=debug msg="obtained new client token: AAGSw48ZG/jUBEBmJeek5cppDaQgWkclVzRFEiX3SqZhsVlFLNC9dDZ8Wu8C/OLo/Lk94KCfNKc1BY+1PJ0+K4VIqXoYjoH8ENv3IRxh+iKkOIXZpLqaHWS/BUK0Aq/FhtuNJ+o0Cuuxhj/x07K4Va+/Se86RxRLN/BM9X0HXw5Uk8fEEYGAeas9eEe1Ndb0phOjS+HuaS8M3IUX/ElX+99l12MGoKpXZJJKS2VV/0/mjP6BwOtbcXc=" Aug 26 00:44:37 volumio go-librespot[1816]: time="2026-08-26T00:44:37+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:37 volumio go-librespot[1816]: time="2026-08-26T00:44:37+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:37 volumio go-librespot[1816]: time="2026-08-26T00:44:37+10:00" level=debug msg="completed challenge" Aug 26 00:44:37 volumio go-librespot[1816]: time="2026-08-26T00:44:37+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:37 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:38 volumio go-librespot[1816]: time="2026-08-26T00:44:38+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:39 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:40 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 26 00:44:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:41 volumio go-librespot[1825]: go-librespot daemon starting... Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10:00" level=debug msg="app state loaded" Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:41 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10: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]" Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10: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]" Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10: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]" Aug 26 00:44:41 volumio go-librespot[1826]: time="2026-08-26T00:44:41+10:00" level=info msg="zeroconf server listening on port 34147" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10:00" level=debug msg="obtained new client token: AAF29z/cK6VRt2yyRdZAuFTN7jJUxChmcslFeobOq8q+wC8POaGRZ9yG73VJUDxX5Ie8/55V2Bk9y7way8Do+TKmiv27NNvaWoGKbBoI1EbS5BQgbjeqrnEud7wm/LiuvF6z9GSjBq60joFo6WprQ+QztP918EnZKB50aZ+d8qUnMslPm+JtK9KDOoWXVPKxkshM2kBHjmuInrLKCYs68m5vqWC/ohwB7AkIvvghr9ZB/KI1oxfJ0No=" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10: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" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10:00" level=debug msg="connected to ap-gae2.spotify.com:443" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10:00" level=debug msg="completed challenge" Aug 26 00:44:42 volumio go-librespot[1826]: time="2026-08-26T00:44:42+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:43 volumio volumio[1385]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 26 00:44:43 volumio volumio[1385]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 26 00:44:43 volumio volumio[1385]: info: Streaming services startup Aug 26 00:44:43 volumio volumio[1385]: info: Starting Streaming Daemon Aug 26 00:44:43 volumio go-librespot[1826]: time="2026-08-26T00:44:43+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:43 volumio volumio[1385]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 26 00:44:43 volumio sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 00:44:43 volumio sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:44:43 volumio sudo[1836]: pam_unix(sudo:session): session closed for user root Aug 26 00:44:44 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:44 volumio volumio[1385]: info: Initializing connection to go-librespot Websocket Aug 26 00:44:44 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 00:44:44 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:44:44 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:44:44 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 26 00:44:44 volumio volumio[1385]: error: Cannot start Volumio Streaming Daemon Aug 26 00:44:44 volumio volumio[1385]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 00:44:44 volumio volumio[1385]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 00:44:45 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:45 volumio volumio[1385]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 00:44:45 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Aug 26 00:44:45 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 00:44:45 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 26 00:44:46 volumio volumio[1385]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 26 00:44:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 26 00:44:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:46 volumio go-librespot[1859]: go-librespot daemon starting... Aug 26 00:44:46 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:46 volumio volumio[1385]: error: MyVolumio Custom Token format not valid, refreshing it Aug 26 00:44:46 volumio go-librespot[1860]: time="2026-08-26T00:44:46+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:46 volumio go-librespot[1860]: time="2026-08-26T00:44:46+10:00" level=debug msg="app state loaded" Aug 26 00:44:46 volumio go-librespot[1860]: time="2026-08-26T00:44:46+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:47 volumio volumio[1385]: SPOTIFY: User informations: {"account_id":"qqkDUiI0bZ","country":"AU","display_name":"Rohan","email":"rohanwalder@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1259087947"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/1259087947","id":"1259087947","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee859a6d2532d7f38d0c446f1b30","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b829a6d2532d7f38d0c446f1b30","width":64}],"product":"premium","type":"user","uri":"spotify:user:1259087947"} Aug 26 00:44:47 volumio volumio[1385]: info: Spotify Successfully logged in Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 00:44:47 volumio volumio[1385]: info: [1787669087256] CoreMusicLibrary::Adding element Spotify Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:44:47 volumio volumio[1385]: Cannot find translation for source Spotify Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10: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]" Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10: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]" Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10: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]" Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10:00" level=info msg="zeroconf server listening on port 34735" Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10:00" level=debug msg="obtained new client token: AAFXUMyK7L7fg0BCC8DMlFDqQmomlYXnrn5M/cTVEBlsKsNE/y47l4yPmZRRK9m9cn1aTHT/HI2sgwAyBF4bCeRiObav7ijGQS0ZC0G830n8rn5qtlgTGwtMHyE9v8b+2J3mLgezIFDaAUeUo4kf8kOsf9sF5B2CHEIQKorTZ3M2uINhV4u8CeED3ToEfJEA404XzVNwuIQ3tm/2jQKTib8ChMpNpjMn/ureDpvgJK3abkLBzR9JjCSLXg==" Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:44:47 volumio volumio[1385]: info: Discovery: Getting this device information Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Aug 26 00:44:47 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:47 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:44:47 volumio go-librespot[1860]: time="2026-08-26T00:44:47+10: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" Aug 26 00:44:48 volumio go-librespot[1860]: time="2026-08-26T00:44:48+10:00" level=debug msg="connected to ap-gae2.spotify.com:443" Aug 26 00:44:48 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:48 volumio go-librespot[1860]: time="2026-08-26T00:44:48+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:48 volumio go-librespot[1860]: time="2026-08-26T00:44:48+10:00" level=debug msg="completed challenge" Aug 26 00:44:48 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 00:44:48 volumio go-librespot[1860]: time="2026-08-26T00:44:48+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:48 volumio volumio[1385]: info: Initializing connection to go-librespot Websocket Aug 26 00:44:48 volumio go-librespot[1860]: time="2026-08-26T00:44:48+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:49 volumio volumio-remote-updater[777]: Test mode disabled Aug 26 00:44:49 volumio volumio-remote-updater[777]: Alpha mode disabled Aug 26 00:44:49 volumio volumio-remote-updater[777]: Alpha legacy test mode disabled Aug 26 00:44:49 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 26 00:44:49 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:49 volumio volumio[1385]: info: Error connecting to go-librespot Websocket: Error: socket hang up Aug 26 00:44:50 volumio volumio[1385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 26 00:44:50 volumio volumio[1385]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

NEW ADDITIONS

","title":"Update v4.119","updateavailable":true} Aug 26 00:44:50 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:50 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 26 00:44:51 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:51 volumio volumio[1385]: error: Cannot refresh My Volumio Token: [object Object] Aug 26 00:44:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 26 00:44:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:51 volumio go-librespot[1870]: go-librespot daemon starting... Aug 26 00:44:51 volumio go-librespot[1871]: time="2026-08-26T00:44:51+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:51 volumio go-librespot[1871]: time="2026-08-26T00:44:51+10:00" level=debug msg="app state loaded" Aug 26 00:44:51 volumio go-librespot[1871]: time="2026-08-26T00:44:51+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Aug 26 00:44:52 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Aug 26 00:44:52 volumio go-librespot[1871]: time="2026-08-26T00:44:52+10: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]" Aug 26 00:44:52 volumio go-librespot[1871]: time="2026-08-26T00:44:52+10: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]" Aug 26 00:44:52 volumio go-librespot[1871]: time="2026-08-26T00:44:52+10: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]" Aug 26 00:44:52 volumio go-librespot[1871]: time="2026-08-26T00:44:52+10:00" level=info msg="zeroconf server listening on port 42905" Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 00:44:52 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=debug msg="obtained new client token: AAH1zVs+YCUGxgmSd25MJD2HB99uXqA6RdxdXzJuxxJBKSe9ZTDi2IZN3FAiRExOZUFQD39VUDqdXSuCj9KDY3hMf9H8gJ9/2NadnlTILcy7/U04fHrAL/CHYtK4jWB7hNMYVGaCmWKWm9f5nVPj5OcdeERbSu807V5ymd5tDJDDijZLkhMFXl12GJo3AndRivMCa5z5MdyH6LPRmCAMOU6+ScMzHVdotxPG1HzZIKLFcF9ILbrWU6E=" Aug 26 00:44:53 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:53 volumio volumio[1385]: info: Initializing connection to go-librespot Websocket Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=debug msg="new websocket client" Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:53 volumio volumio[1385]: info: Connection to go-librespot Websocket established Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=debug msg="completed challenge" Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:53 volumio go-librespot[1871]: time="2026-08-26T00:44:53+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:54 volumio volumio[1385]: info: Connection to go-librespot Websocket closed Aug 26 00:44:54 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:55 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -5 Aug 26 00:44:56 volumio volumio[1385]: info: Getting Spotify volume Aug 26 00:44:56 volumio volumio[1385]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:44:56 volumio volumio[1385]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 00:44:56 volumio volumio[1385]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 26 00:44:56 volumio volumio[1385]: errno: -111, Aug 26 00:44:56 volumio volumio[1385]: code: 'ECONNREFUSED', Aug 26 00:44:56 volumio volumio[1385]: syscall: 'connect', Aug 26 00:44:56 volumio volumio[1385]: address: '127.0.0.1', Aug 26 00:44:56 volumio volumio[1385]: port: 9879, Aug 26 00:44:56 volumio volumio[1385]: response: undefined Aug 26 00:44:56 volumio volumio[1385]: } Aug 26 00:44:56 volumio volumio[1385]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:44:56 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 26 00:44:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:57 volumio go-librespot[1898]: go-librespot daemon starting... Aug 26 00:44:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10:00" level=debug msg="app state loaded" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10: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]" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10: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]" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10: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]" Aug 26 00:44:57 volumio go-librespot[1907]: time="2026-08-26T00:44:57+10:00" level=info msg="zeroconf server listening on port 37585" Aug 26 00:44:58 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:44:58 volumio go-librespot[1907]: time="2026-08-26T00:44:58+10:00" level=debug msg="obtained new client token: AAE/yS6SU336YvwEA8VCBPIrtcyI25mB2pgnERR9qYjj8RuCKWSrjHiOgK6GrOu/nMnYB4HBrWWFhkc2FgkrQTuCd07pGwSwJcwnfFZHjTGAs6oriiJdJmQgllk7gdsB0DrCRH3sTpfbKQK0Htwazi8R1YmK8LHScXkyPpTq33AjTIbZdFZwcEJudQeu77UOooes+i6hKRGkYFdTKn16Jy+DpFVcOClXDfGfEq6pi7J+ZNzx5l+V1FA=" Aug 26 00:44:58 volumio go-librespot[1907]: time="2026-08-26T00:44:58+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:44:58 volumio go-librespot[1907]: time="2026-08-26T00:44:58+10:00" level=debug msg="completed keyexchange" Aug 26 00:44:58 volumio go-librespot[1907]: time="2026-08-26T00:44:58+10:00" level=debug msg="completed challenge" Aug 26 00:44:58 volumio go-librespot[1907]: time="2026-08-26T00:44:58+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:44:59 volumio go-librespot[1907]: time="2026-08-26T00:44:59+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:44:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:44:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:44:59 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:01 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 26 00:45:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:45:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:45:02 volumio go-librespot[1918]: go-librespot daemon starting... Aug 26 00:45:02 volumio go-librespot[1919]: time="2026-08-26T00:45:02+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:45:02 volumio go-librespot[1919]: time="2026-08-26T00:45:02+10:00" level=debug msg="app state loaded" Aug 26 00:45:02 volumio go-librespot[1919]: time="2026-08-26T00:45:02+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:45:03 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10: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]" Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10: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]" Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10: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]" Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10:00" level=info msg="zeroconf server listening on port 35381" Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10:00" level=debug msg="obtained new client token: AAHIQRHrpqIgz9nDY9Q0HDOaAxGU3ME/owQJdg/YPL5G1Dfydxtqi/YAfnlxL1hiRIQCrSU785UY4w4UlFztVknrPDL8YNZQm5YwJHzCPMRHil7aC0wRQrTN7+J9UZkTduf+rPtiFX9+3rnlO3OyAhICKJFcOBciwFZ0B3UVKbe9dXkvaeJHFeb0slGa/UiN9rc7GrYeQyLZDswnMuPuuPon/xmEi6ptqr5ltbbbozLrH2/3SYGOIPYqfw==" Aug 26 00:45:03 volumio go-librespot[1919]: time="2026-08-26T00:45:03+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:45:04 volumio go-librespot[1919]: time="2026-08-26T00:45:04+10:00" level=debug msg="completed keyexchange" Aug 26 00:45:04 volumio go-librespot[1919]: time="2026-08-26T00:45:04+10:00" level=debug msg="completed challenge" Aug 26 00:45:04 volumio go-librespot[1919]: time="2026-08-26T00:45:04+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:45:04 volumio go-librespot[1919]: time="2026-08-26T00:45:04+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:45:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:45:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:45:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:05 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:07 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:07 volumio sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 00:44' Aug 26 00:45:07 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:45:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 26 00:45:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:45:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:45:07 volumio go-librespot[1946]: go-librespot daemon starting... Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10:00" level=debug msg="app state loaded" Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:45:08 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10: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]" Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10: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]" Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10: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]" Aug 26 00:45:08 volumio go-librespot[1947]: time="2026-08-26T00:45:08+10:00" level=info msg="zeroconf server listening on port 35725" 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"