Aug 28 11:34:01 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 28 11:34:01 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:01 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:01 streamer-hifi go-librespot[2019]: go-librespot daemon starting... Aug 28 11:34:01 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:01+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:01 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:01+02:00" level=debug msg="app state loaded" Aug 28 11:34:01 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=info msg="zeroconf server listening on port 41429" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:02 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="obtained new client token: AAFbirf+J0GWWF0CyWk4g+HB3WrWmTqb4BYgUVoZpfe5QE5gmoepTFhKC7BFZdtkVCurDn7NM7FBPxNS3WD1UIkSdhHzKy0tF22KOEvg5jgGjl71QTLOSq0Km6xTSYm8y2yOUFBYnX9FP5WoHYisw3dR7BQGyjk44zgaNFGT/IrTuEbaN3sJJlf71uUgNeVY2JYUq47VbOhfP/jAMRpEh23xYGrk0MmggNiyBLE+/Mp8K89iSUVzjA==" Aug 28 11:34:02 streamer-hifi volumio[1994]: info: ----- Volumio3 ---- Aug 28 11:34:02 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:02 streamer-hifi volumio[1994]: info: ----- System startup ---- Aug 28 11:34:02 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=debug msg="completed challenge" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:02 streamer-hifi go-librespot[2020]: time="2026-08-28T11:34:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:02 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:02 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:04 streamer-hifi volumio[1994]: info: MYVOLUMIO Environment detected Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Plugin folders cleanup Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning into folder /volumio/app/plugins/ Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category audio_interface Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category miscellanea Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category music_service Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category plugins.json Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category system_controller Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category user_interface Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning into folder /data/plugins/ Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category music_service Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category system_controller Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Scanning category user_interface Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Plugin folders cleanup completed Aug 28 11:34:04 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:04 streamer-hifi volumio[1994]: info: ----- Core plugins startup ---- Aug 28 11:34:04 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Adding plugin upnp to MyMusic Plugins Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Loading plugins from folder /data/plugins/ Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Loading plugin "system"... Aug 28 11:34:04 streamer-hifi volumio[1994]: info: Loading plugin "appearance"... Aug 28 11:34:05 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 28 11:34:05 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:05 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:05 streamer-hifi go-librespot[2041]: go-librespot daemon starting... Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="app state loaded" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=info msg="zeroconf server listening on port 45663" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="obtained new client token: AAEAVNZQjdj347s0x81gtOo2iP/0M3eNCroI4eSC09+dE3hCIVhqMfeZvNLJf+/5nxzzPkVHnVN2qChDLofk8ZCiZg3CerMJ9+Or2Hcp6iBTqUYIUTiL0K6+fv0gYlKdaA/WquMp1/3JeTT822C85nXFPLT2qIX0fcPmtGjwqeH5yfC1M0/j65/rF12MFp7FgY50ktGr/CK7nXNzN4WyVkeXJwJiXcKoA8S1MFX2WkMm6BA4tt4SyySl" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:06 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:06+02:00" level=debug msg="completed challenge" Aug 28 11:34:07 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:07+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:07 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:34:07] [connect] Successful connection Aug 28 11:34:07 streamer-hifi go-librespot[2042]: time="2026-08-28T11:34:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:07 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:07 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "network"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Refreshing Cached IP Addresses Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "services"... Aug 28 11:34:08 streamer-hifi sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:34:08 streamer-hifi sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:08 streamer-hifi sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "volumio5onboarding"... Aug 28 11:34:08 streamer-hifi sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:08 streamer-hifi sudo[2054]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:08 streamer-hifi sudo[2052]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "alsa_controller"... Aug 28 11:34:08 streamer-hifi sudo[2061]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 11:34:08 streamer-hifi sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:08 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "wizard"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "networkfs"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Starting Udev Watcher for removable devices Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Ignoring mount for partition: boot Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Ignoring mount for partition: volumio Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Ignoring mount for partition: volumio_data Aug 28 11:34:08 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "volumio_command_line_client"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "upnp"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: [1787909648846] Starting Upmpd Daemon Aug 28 11:34:08 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "my_music"... Aug 28 11:34:08 streamer-hifi volumio[1994]: info: Loading plugin "mpd"... Aug 28 11:34:09 streamer-hifi sudo[2061]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:09 streamer-hifi volumio[1994]: info: Loading plugin "upnp_browser"... Aug 28 11:34:10 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 28 11:34:10 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:10 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:10 streamer-hifi go-librespot[2082]: go-librespot daemon starting... Aug 28 11:34:10 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:10.542+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:38958->127.0.0.1:3000: i/o timeout" Aug 28 11:34:10 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:10+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:10 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:10+02:00" level=debug msg="app state loaded" Aug 28 11:34:10 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=info msg="zeroconf server listening on port 43577" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="obtained new client token: AAEvZJ4mRK0hIhkID0aUqcx4dE8MfdKhhk78za/0G1PgTtgOfruSeIEv6HPrfR4EgQ3nXMN2cQku3dTjs+/JRywVmekBKqOc/Zvg7V2DK5b7o5wege8LvYp7uhF4cedw5TECAbmusg8pKjTVvAoy6TxrHq2+6OudZGjlg6JZblN81h89Yf5hj04REswnmCk866CipdRVb33bU02e7zEK3fonHXSd3EGw68RFVwO7d9Pw82cBfygTIL0e" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=debug msg="completed challenge" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:11 streamer-hifi go-librespot[2083]: time="2026-08-28T11:34:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:11 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:11 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Starting UPNP Browser Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Loading plugin "alarm-clock"... Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Loading plugin "airplay_emulation"... Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Starting Shairport Sync Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Loading plugin "last_100"... Aug 28 11:34:14 streamer-hifi volumio[1994]: info: Loading plugin "webradio"... Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Loading plugin "i2s_dacs"... Aug 28 11:34:15 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 28 11:34:15 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Loading plugin "volumiodiscovery"... Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** For more information see Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 11:34:15 streamer-hifi volumio[1994]: *** WARNING *** For more information see Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** For more information see Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 11:34:15 streamer-hifi node[1994]: *** WARNING *** For more information see Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Discovery: Started advertising with name: Volumio Aug 28 11:34:15 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:15 streamer-hifi go-librespot[2092]: go-librespot daemon starting... Aug 28 11:34:15 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 11:34:15 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:15+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:15 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:15+02:00" level=debug msg="app state loaded" Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Plugin rp2 is not enabled Aug 28 11:34:15 streamer-hifi volumio[1994]: info: Loading plugin "spop"... Aug 28 11:34:15 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=info msg="zeroconf server listening on port 43291" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="obtained new client token: AAFIAGMHlYRfmVvEONSa2ymk0dSEiTn1Sg1kjdS6o5Mj7DDSJO3CrQp7o2rik4NkDHAqAajnV53VvWlcf+dI5fua2cJNZBJgMQAOk1c1xjcw8KofVVgMsXaLpvDfc89n9eY+2um74LHPqrbkgnCACz/UVvxmykmctO8OWGLt0E7PpbN+z3A8nNrzG0m1aORCnCDqOxqbVOlKh4V7rMSC7nbvQ3GGuFV4aeZ44dvUKzoKrj1rT3LDhg==" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=debug msg="completed challenge" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:16 streamer-hifi go-librespot[2093]: time="2026-08-28T11:34:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:16 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:16 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:18 streamer-hifi volumio[1994]: info: Loading plugin "outputs"... Aug 28 11:34:18 streamer-hifi volumio[1994]: info: Loading plugin "albumart"... Aug 28 11:34:18 streamer-hifi volumio[1994]: info: Plugin example_plugin is not enabled Aug 28 11:34:18 streamer-hifi volumio[1994]: info: Loading plugin "inputs"... Aug 28 11:34:18 streamer-hifi volumio[1994]: info: Loading plugin "updater_comm"... Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Plugin mpdemulation is not enabled Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Loading plugin "rest_api"... Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Loading plugin "websocket"... Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Starting Socket.io Server version 1.7.4 Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Loading plugin "motherearthradio"... Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Plugin radio_paradise is not enabled Aug 28 11:34:19 streamer-hifi volumio[1994]: info: Loading plugin "rpi_eeprom_updater"... Aug 28 11:34:19 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 28 11:34:19 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:19.801+02:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Aug 28 11:34:19 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:20 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:20 streamer-hifi go-librespot[2130]: go-librespot daemon starting... Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=debug msg="app state loaded" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:20 streamer-hifi volumio[1994]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=info msg="zeroconf server listening on port 36087" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:20 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:20+02:00" level=debug msg="obtained new client token: AAGxYviv1tItQxcV6tPxfdoys/MLlXEpKOVWOgGKL2FUEwccWc2Wx+emsqcUyGFX/gUcvY9bwk2LeWRdSm8jw6ZJsGBEVI28wKnwdKKhlzv4OpxCkhKHsiQwZj9WJQrf53b09p2tQ98agZVdhcqXQwp4QmfffduvUwQYwvkURheVX/9/JSEOd9WlQ0KSxYCkp2vsmr92w5SXPYaVgfr4jKi2UxV5sLo46gHR6765kNN5qiOBacC4bq58" Aug 28 11:34:21 streamer-hifi volumio[1994]: info: Loading plugin "Systeminfo"... Aug 28 11:34:21 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:21 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:21+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:21 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:21+02:00" level=debug msg="completed challenge" Aug 28 11:34:21 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:21+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:21 streamer-hifi go-librespot[2131]: time="2026-08-28T11:34:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:21 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:21 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:21 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:21.544+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:33566->127.0.0.1:3000: i/o timeout" Aug 28 11:34:22 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:34:22] [connect] Successful connection Aug 28 11:34:22 streamer-hifi volumio[2119]: Forking 3 albumart workers Aug 28 11:34:24 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 28 11:34:24 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:24 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:24 streamer-hifi go-librespot[2170]: go-librespot daemon starting... Aug 28 11:34:24 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:24 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:24+02:00" level=debug msg="app state loaded" Aug 28 11:34:24 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:24 streamer-hifi volumio[1994]: info: Loading i18n strings for locale fr Aug 28 11:34:25 streamer-hifi volumio[1994]: Updating browse sources language Aug 28 11:34:25 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=info msg="zeroconf server listening on port 46149" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="obtained new client token: AAGkGXU8rbuC9GSuew/fHy9rtqHHIn4Yzg6aP/FUavUZedA5BEnY+tx71892h9J8VxNRnetTyRSgwitzENDjZtH9jRYdN+WkCEfh0Cd5gZoNuemYaeWfn2fnOKccAT17ORvVU8fUtmZF9uBnWrpOXCxxl3KnuCB6kqbgYw6x4vavj+sAIY/ylnmOq9OY8BbSvj0h+gqNkDbV4PTnXe7+qK28qNdDdMbMlN0l74nORWNA9R5HOS2ruMuT" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=debug msg="completed challenge" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:25 streamer-hifi go-librespot[2171]: time="2026-08-28T11:34:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:25 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:25 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::initPlayerControls Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:27 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:34:28 streamer-hifi volumio[1994]: Express server listening on port 3000 Aug 28 11:34:28 streamer-hifi volumio[1994]: [Metrics] WebUI: 28s 263.73ms Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreStateMachine::resetVolumioState Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreStateMachine::getcurrentVolume Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreStateMachine::pushState Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:34:28 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioPushState Aug 28 11:34:29 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 28 11:34:29 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:29 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:29 streamer-hifi go-librespot[2197]: go-librespot daemon starting... Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=debug msg="app state loaded" Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:29 streamer-hifi sudo[2210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:34:29 streamer-hifi sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:29 streamer-hifi sudo[2210]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=info msg="zeroconf server listening on port 41739" Aug 28 11:34:29 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:29+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:30 streamer-hifi sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:34:30 streamer-hifi sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=debug msg="obtained new client token: AAHVX1retmH4OJwK134UPf8BKRHzjPAPpr03Nq6DLBNboh+8pjS8wYP73uV53l1oYTdWOXtOYFcYr5xVpPC77GRjjNb6I1MqCfwHkwD2+YpxO5p8JyRwkb2PfNSiphWuGVvMFGJLx/1WKT5eHe9PPn98ga2XPDkLpGi4mWnfWOdXHtnUhhFdOkuMv2Z7J5oRk4RFGaDfcIsDHVGtGnLQMj4qYZTlFMdT7tbLPFXvATL09eoChkSV4Q==" Aug 28 11:34:30 streamer-hifi sudo[2208]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=debug msg="completed challenge" Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:30 streamer-hifi volumio[1994]: info: Volumio Network Manager: Network status updated: 2 Aug 28 11:34:30 streamer-hifi go-librespot[2198]: time="2026-08-28T11:34:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:30 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:30 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:32 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:32.555+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:40402->127.0.0.1:3000: i/o timeout" Aug 28 11:34:32 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:32 streamer-hifi volumio[1994]: info: Reloading queue from file Aug 28 11:34:32 streamer-hifi volumio[1994]: info: CoreStateMachine::setRepeat undefined single undefined Aug 28 11:34:32 streamer-hifi volumio[1994]: info: CoreStateMachine::pushState Aug 28 11:34:32 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:34:32 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioPushState Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CoreStateMachine::setRandom true Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CoreStateMachine::pushState Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:34:33 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioPushState Aug 28 11:34:33 streamer-hifi volumio[1994]: info: Setting Device type: Raspberry PI Aug 28 11:34:33 streamer-hifi volumio[1994]: info: Completed loading Core Plugins Aug 28 11:34:33 streamer-hifi volumio[1994]: info: Preparing to generate the ALSA configuration file Aug 28 11:34:33 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 28 11:34:33 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:33 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:33 streamer-hifi go-librespot[2220]: go-librespot daemon starting... Aug 28 11:34:33 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:33+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:33 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:33+02:00" level=debug msg="app state loaded" Aug 28 11:34:33 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=info msg="zeroconf server listening on port 42525" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="obtained new client token: AAFq1vLl3obAmvSGuRXcLY7jso0K4MZLXZMx+qctu91/NOaGmUOkQ0akhfvz4YL0DaP4iMx77w9Ikozb05qS4wdSvAYt3kyow83IsPKjsiOc75igJs54i9v3DIngI6Y8BYAjTxKWDtdWiY3xWcOA68qAiMZOigYsWriWvXxwLZ3IQyqwIrXrNsr1twa+T6BASSKNrvqQ8wKZSXKpz4m8W0QYHz8CnO3fBIMczTYJDu2XrOFLPmFvwOFR" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=debug msg="completed challenge" Aug 28 11:34:34 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:34+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:35 streamer-hifi sudo[2232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 28 11:34:35 streamer-hifi sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:35 streamer-hifi go-librespot[2221]: time="2026-08-28T11:34:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:35 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:35 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:35 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Aug 28 11:34:35 streamer-hifi sudo[2232]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:35 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Aug 28 11:34:35 streamer-hifi volumio[1994]: info: Upmpdcli Daemon Started Aug 28 11:34:35 streamer-hifi volumio[1994]: info: Asound.conf file unchanged, so no further update is needed Aug 28 11:34:35 streamer-hifi volumio[1994]: info: Output device has changed, restarting MPD Aug 28 11:34:35 streamer-hifi volumio[1994]: info: Output device has changed, restarting Shairport Sync Aug 28 11:34:35 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:35 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:35 streamer-hifi sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 11:34:35 streamer-hifi sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:35 streamer-hifi volumio[1994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 11:34:35 streamer-hifi sudo[2238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 11:34:35 streamer-hifi volumio[1994]: info: ___________ START PLUGINS ___________ Aug 28 11:34:35 streamer-hifi sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:35 streamer-hifi sudo[2236]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:36 streamer-hifi volumio[1994]: info: ControllerMpd::onStart: Initializing MPD Aug 28 11:34:36 streamer-hifi volumio[1994]: info: Creating MPD Configuration file Aug 28 11:34:36 streamer-hifi systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 28 11:34:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:34:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 11:34:36 streamer-hifi volumio[1994]: info: [1787909676505] CoreMusicLibrary::Adding element Serveurs Média Aug 28 11:34:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:34:36 streamer-hifi sudo[2246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 28 11:34:36 streamer-hifi sudo[2246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:36 streamer-hifi sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 11:34:36 streamer-hifi volumio[1994]: info: UPNP Browser: Client initialized successfully Aug 28 11:34:36 streamer-hifi sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 11:34:36 streamer-hifi sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:36 streamer-hifi sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:36 streamer-hifi systemd[1]: mpd.service: Deactivated successfully. Aug 28 11:34:36 streamer-hifi systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 11:34:36 streamer-hifi systemd[1]: mpd.service: Consumed 10.455s CPU time. Aug 28 11:34:37 streamer-hifi systemd[1]: mpd.socket: Deactivated successfully. Aug 28 11:34:37 streamer-hifi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 11:34:37 streamer-hifi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 11:34:37 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:34:37] [connect] Successful connection Aug 28 11:34:37 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:37 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:37 streamer-hifi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 11:34:37 streamer-hifi systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 11:34:37 streamer-hifi sudo[2248]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:37 streamer-hifi systemd[1]: mpd.service: Deactivated successfully. Aug 28 11:34:37 streamer-hifi systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 11:34:37 streamer-hifi systemd[1]: mpd.socket: Deactivated successfully. Aug 28 11:34:37 streamer-hifi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 11:34:37 streamer-hifi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 11:34:37 streamer-hifi sudo[2246]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:37 streamer-hifi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 11:34:37 streamer-hifi systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 11:34:37 streamer-hifi volumio[1994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 11:34:37 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:37 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:37 streamer-hifi sudo[2262]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 11:34:37 streamer-hifi sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 11:34:37 streamer-hifi sudo[2262]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:38 streamer-hifi volumio[1994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 11:34:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 11:34:38 streamer-hifi volumio[1994]: info: [1787909678170] CoreMusicLibrary::Adding element Last_100 Aug 28 11:34:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:34:38 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 28 11:34:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 11:34:38 streamer-hifi volumio[1994]: info: [1787909678309] CoreMusicLibrary::Adding element Webradio Aug 28 11:34:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:34:38 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:38 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:38 streamer-hifi go-librespot[2269]: go-librespot daemon starting... Aug 28 11:34:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:34:38 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:38+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:38 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:38+02:00" level=debug msg="app state loaded" Aug 28 11:34:38 streamer-hifi volumio[1994]: info: Initializing BBC Radios Aug 28 11:34:38 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=info msg="zeroconf server listening on port 42007" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="obtained new client token: AAERMMNetLDL8+yJ/Fwq6LZXJ8oNFguzIcLTyyAG4tnulsJXDqvr01obI9ZPoj+TfuFBV/JDma3bhh6MSCjGSlk6t7Mdb6AoCVUgLUOsss/8HBdwzIGGbauRofwxREfOmAYM+QRDtC/bRYXgpH7V27K6ISp3/KGJQdYTK7bdXc5iJAECtNN9hSXNF2L0dZ3owxZl/zlU1F7is0k8J//9TsRUX8djGhLUgVlEWpDw9cTwDvZ9VbdNO4x+" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=debug msg="completed challenge" Aug 28 11:34:39 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:39+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:40 streamer-hifi go-librespot[2270]: time="2026-08-28T11:34:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:40 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:40 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:40 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:34:40 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:40 streamer-hifi volumio[1994]: info: Creating Spotify config file Aug 28 11:34:40 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:40 streamer-hifi volumio[2140]: Starting albumart workers Aug 28 11:34:42 streamer-hifi volumio[2141]: Starting albumart workers Aug 28 11:34:43 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 28 11:34:43 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:43 streamer-hifi mpd[2266]: 2026-08-28T11:34:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 11:34:43 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:43 streamer-hifi go-librespot[2306]: go-librespot daemon starting... Aug 28 11:34:43 streamer-hifi systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 11:34:43 streamer-hifi sudo[2238]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:43 streamer-hifi go-librespot[2309]: time="2026-08-28T11:34:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:43 streamer-hifi sudo[2250]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:44 streamer-hifi go-librespot[2309]: time="2026-08-28T11:34:44+02:00" level=info msg="zeroconf server listening on port 39725" Aug 28 11:34:44 streamer-hifi go-librespot[2309]: time="2026-08-28T11:34:44+02:00" level=info msg="using built-in mDNS responder" Aug 28 11:34:44 streamer-hifi volumio[2142]: Starting albumart workers Aug 28 11:34:45 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:45.555+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:39470->127.0.0.1:3000: i/o timeout" Aug 28 11:34:46 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 11:34:46 streamer-hifi volumio[1994]: info: [1787909686639] CoreMusicLibrary::Adding element Mother Earth Radio Aug 28 11:34:46 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:34:46 streamer-hifi volumio[1994]: Cannot find translation for source Mother Earth Radio Aug 28 11:34:46 streamer-hifi volumio[1994]: info: [MER] Plugin started (SSE mode) Aug 28 11:34:47 streamer-hifi volumio[1994]: info: [RpiEepromUpdater] Plugin started successfully Aug 28 11:34:47 streamer-hifi volumio[1994]: info: Volumio Calling Home Aug 28 11:34:50 streamer-hifi volumio[1994]: info: Discovery: adding 1c4c51c0-72a1-40ef-994f-91b72f6ede08 Aug 28 11:34:50 streamer-hifi volumio[1994]: info: Discovery: Found device Volumio Aug 28 11:34:50 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioGetState Aug 28 11:34:50 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:34:51 streamer-hifi volumio[1994]: info: Discovery: this is already registered, 1c4c51c0-72a1-40ef-994f-91b72f6ede08 Aug 28 11:34:51 streamer-hifi volumio[1994]: info: Discovery: Found device Volumio Aug 28 11:34:51 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioGetState Aug 28 11:34:51 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:34:52 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:34:52] [connect] Successful connection Aug 28 11:34:52 streamer-hifi volumio[1994]: info: MPD Permissions set Aug 28 11:34:52 streamer-hifi volumio[1994]: info: Completed starting Core Plugins Aug 28 11:34:52 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:52 streamer-hifi volumio[1994]: info: ----- MyVolumio plugins startup ---- Aug 28 11:34:52 streamer-hifi volumio[1994]: info: ------------------------------------------- Aug 28 11:34:52 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 11:34:52 streamer-hifi volumio[1994]: info: MPD Permissions set Aug 28 11:34:53 streamer-hifi volumio[1994]: info: Volumio called home Aug 28 11:34:53 streamer-hifi volumio[1994]: info: Spotify config file written Aug 28 11:34:53 streamer-hifi sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 28 11:34:53 streamer-hifi sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:34:53 streamer-hifi systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 28 11:34:54 streamer-hifi systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 28 11:34:54 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:54 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:54 streamer-hifi go-librespot[2366]: go-librespot daemon starting... Aug 28 11:34:54 streamer-hifi sudo[2364]: pam_unix(sudo:session): session closed for user root Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="app state loaded" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=info msg="zeroconf server listening on port 40947" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="obtained new client token: AAFRpe/31OqnxYeTRMMxEkV4p+G7bWgzADPOVxy9Ox/Vi2mlGI3P5e+jAhKvUjZClJNgzZfekGA5R+Ag5Hd8BT23tARw9K47CZJZwoNjhfQHspNlk3kVDVaOFsea+Kp5XG+bX4G6E4P5BDk2zAwiI73YXlssQCR4Q+6kPnRjgc/JySRPRd4kc/LEmokUVwBnmyAxagp4rAiPjqZ//atqXigr5cjmnI8WtnZlQmesccWT4ILO+te1bECj" Aug 28 11:34:54 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:54+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:55 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:55+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:55 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:55+02:00" level=debug msg="completed challenge" Aug 28 11:34:55 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:55+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:55 streamer-hifi go-librespot[2367]: time="2026-08-28T11:34:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:34:55 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:34:55 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:34:56 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:34:56.559+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:35708->127.0.0.1:3000: i/o timeout" Aug 28 11:34:56 streamer-hifi volumio[1994]: error: MPD error: The expression evaluated to a falsy value: Aug 28 11:34:56 streamer-hifi volumio[1994]: assert.ok(self.idling) Aug 28 11:34:56 streamer-hifi volumio[1994]: error: The expression evaluated to a falsy value: Aug 28 11:34:56 streamer-hifi volumio[1994]: assert.ok(self.idling) Aug 28 11:34:56 streamer-hifi volumio[1994]: info: MPD running with PID2266 Aug 28 11:34:56 streamer-hifi volumio[1994]: ,establishing connection Aug 28 11:34:56 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:56 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:56 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:56 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:56 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:34:57 streamer-hifi volumio[1994]: info: No need to fix Spotify hosts Aug 28 11:34:58 streamer-hifi volumio[1994]: error: updateQueue error: null Aug 28 11:34:58 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 28 11:34:58 streamer-hifi volumio[1994]: info: New Spotify access tokenBQArmWl1Ie... Aug 28 11:34:58 streamer-hifi volumio[1994]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 28 11:34:58 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 28 11:34:58 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:58 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:34:58 streamer-hifi go-librespot[2390]: go-librespot daemon starting... Aug 28 11:34:58 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:58+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:34:58 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:58+02:00" level=debug msg="app state loaded" Aug 28 11:34:58 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:34:59 streamer-hifi volumio[1994]: error: updateQueue error: null Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=info msg="zeroconf server listening on port 37633" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:34:59 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="obtained new client token: AAHKAhK7u2fJaWUCNz+35fcAp17StTGcWbOvM7ORoiJhyyiIRF413MIG+zDc68V5SC2G2W+/t9Sjw1OKlZxOGmTQ2jTnU6JlEPCjS8kINol394jERUVJHmCKqs6C0+u4/6IPcVlVO6/06H8euv+3EScTepWjMFDrDAUtN8PjFCWScAu2wxIaxp3qEJdtlT5JUgYzSA8b3x3B95qShTD3YormcrjLR3TiMf4e1bJ66vhSWvmUL2QdcOH4" Aug 28 11:34:59 streamer-hifi volumio[1994]: info: Starting Shairport Sync Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:34:59 streamer-hifi volumio[1994]: info: Starting Shairport Sync Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="completed keyexchange" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=debug msg="completed challenge" Aug 28 11:34:59 streamer-hifi go-librespot[2391]: time="2026-08-28T11:34:59+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:34:59 streamer-hifi volumio[1994]: info: Starting Shairport Sync Aug 28 11:34:59 streamer-hifi sudo[2401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 11:34:59 streamer-hifi sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:00 streamer-hifi go-librespot[2391]: time="2026-08-28T11:35:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:00 streamer-hifi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 11:35:00 streamer-hifi systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 11:35:00 streamer-hifi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi systemd[1]: shairport-sync.service: Consumed 2.692s CPU time. Aug 28 11:35:00 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:00 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:00 streamer-hifi sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 11:35:00 streamer-hifi sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:00 streamer-hifi sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 11:35:00 streamer-hifi sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:00 streamer-hifi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi sudo[2401]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:00 streamer-hifi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 11:35:00 streamer-hifi systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 11:35:00 streamer-hifi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 11:35:00 streamer-hifi systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 11:35:00 streamer-hifi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 11:35:00 streamer-hifi sudo[2406]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:00 streamer-hifi sudo[2403]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:01 streamer-hifi volumio[1994]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 28 11:35:01 streamer-hifi volumio[1994]: info: Shairport-Sync Started Aug 28 11:35:01 streamer-hifi volumio[1994]: Error adding Membership: Error: addMembership EINVAL Aug 28 11:35:01 streamer-hifi volumio[1994]: info: Shairport-Sync Started Aug 28 11:35:01 streamer-hifi volumio[1994]: info: go-librespot daemon successfully initialized Aug 28 11:35:02 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 28 11:35:02 streamer-hifi volumio[1994]: info: Shairport-Sync Started Aug 28 11:35:03 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 28 11:35:03 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioGetState Aug 28 11:35:03 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:35:03 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 28 11:35:03 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:03 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:03 streamer-hifi go-librespot[2457]: go-librespot daemon starting... Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=debug msg="app state loaded" Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin bluetooth to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin multiroom to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin metavolumio to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin cd_controller to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 28 11:35:03 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:03 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:03+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=info msg="zeroconf server listening on port 38957" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=debug msg="obtained new client token: AAHlvzLfOUbxnKVfHQiHPAAZEtW7oXy6IgOeAfXeYxwriSQ+80/s6s6An/tN6yqkY38OB2miCbjovWEhJp+P/NF4a7MvsSXhr92H2DTqn7/KfzX4AvU/Ytg8sf0pi0UkP9Qtd67Mja107tstgx6u218Xj4TG6ub8xW6w/la3OyFV2KCh3Spvk8ddyOcwdfU7LkqZQz4o3UHLdnufRZg9mJktM0XRhpfoWiIGvp/ICeDutKXkPQ+x+Q==" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=debug msg="completed challenge" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:04 streamer-hifi go-librespot[2458]: time="2026-08-28T11:35:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:04 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:04 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:07 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:35:07] [connect] Successful connection Aug 28 11:35:07 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 28 11:35:07 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:07 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:07 streamer-hifi go-librespot[2468]: go-librespot daemon starting... Aug 28 11:35:07 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:07+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:07 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:07+02:00" level=debug msg="app state loaded" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=info msg="zeroconf server listening on port 45343" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="obtained new client token: AAHMqw5B0i8ookVaaM3Gov+5+ZQ9mPrHhE5gYrfPboL41YZt9NyimyIwW/Y2V2+dvyk8kazvpmNJerfcUKQIUNDi4FJliBfKHOLT3Z8m5gRB9C0pgkPMW/tD2zdbCewiApUg+5RR8vmUSJl2tByvVFhKJ+OmY0r0f0ycif4J4BIBpafStZcB+LlRe1L99bER+U4hH5kqmsBPuNPrD0cufAFJN/dZ4tyQEmkxEV9+wvQDexYN8tj/O8EI" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:08 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:09 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:08+02:00" level=debug msg="completed challenge" Aug 28 11:35:09 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:09+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:09 streamer-hifi go-librespot[2469]: time="2026-08-28T11:35:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:09 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:09 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:12 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 28 11:35:12 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:12 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:12 streamer-hifi go-librespot[2492]: go-librespot daemon starting... Aug 28 11:35:12 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:12+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:12 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:12+02:00" level=debug msg="app state loaded" Aug 28 11:35:12 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:13 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:35:13.007+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:42710->127.0.0.1:3000: i/o timeout" Aug 28 11:35:13 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:13 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:13 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:13 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:13+02:00" level=info msg="zeroconf server listening on port 40187" Aug 28 11:35:13 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=debug msg="obtained new client token: AAFfJno46KsOAbxyk83KO83etv+A/G7Nml7U1pf8wi9KhyOiX2Ya+47AsRevEY6ehqF6Z37oMpuhQbKHpD5vOEnPsZyaCNpfiAye2UN41coRP55nGAnUsJwvhVDOgRhNR3e0bNCgX3Wk/HtwkbTj1ZgQikZ8f9gXJ60SLxoiWXFF1qFMe/KjIYX1Tq6r5VPp9MUiFqu0jxOH7hVBdKA6QxECf7JS5HyrYbHaoIZzJSwllAqFVLANj6YO" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=debug msg="completed challenge" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:14 streamer-hifi go-librespot[2493]: time="2026-08-28T11:35:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:14 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:14 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:18 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 28 11:35:18 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:18 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:18 streamer-hifi go-librespot[2505]: go-librespot daemon starting... Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=debug msg="app state loaded" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:18 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 28 11:35:18 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 28 11:35:18 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:18 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:18 streamer-hifi volumio[1994]: info: Starting MyVolumio Remote Streaming Endpoints Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=info msg="zeroconf server listening on port 39533" Aug 28 11:35:18 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:18+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:19 streamer-hifi volumio[1994]: info: MyVolumio login type: Token Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=debug msg="obtained new client token: AAEZ4cbp/d0KrojONNzT5i10xCZ4LihN9QUIbvz4ADWkLevcpbrfDqRGE3oK9Tg1vohVL6e+JqbW4VqO9Ia5Y6Pf2MQXNHvJAxPYvaecfTCC0PfHQu2AEHIks5iLlLHCIdW2AorgcfpZruKqCyuKLKUgOHqLkZXSr0vvubmPW8lWVTauq8RUlnkDPRXiL5SEvFT4dQCd5pQGboX0ZeZty/q6zNnjn7ZqYPV/LiUSUYGpBvPHGoO5Sw==" Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=debug msg="completed challenge" Aug 28 11:35:19 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 28 11:35:19 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:19 streamer-hifi go-librespot[2506]: time="2026-08-28T11:35:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:19 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:19 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:22 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:35:22] [connect] Successful connection Aug 28 11:35:22 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 28 11:35:22 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:22 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:22 streamer-hifi go-librespot[2523]: go-librespot daemon starting... Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="app state loaded" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=info msg="zeroconf server listening on port 37851" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="obtained new client token: AAHGYwjFvSuCib3yjXOGyPXdBRXefTHKdRt8pY2Ii6acL7rBs8RCHTT3F4L4GoE/Gx6EbBk076l3nUmQSYn9K3/VugacLBIONHiDJdA4kJQsZA7YOipItQeAewk2DJvCK/HHg+nQPsLVQq5mw0TkrGDZlnoBNGcp6ctcwSZ/I9VX+3TNwa/5vWio049RX1WpXbajR4BiyxcqzxG1xPZX3q/U0orMEq6+gQpqe7rro+fsQ3tRZPinLYvr" Aug 28 11:35:23 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:23+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:24 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:35:24.008+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:60372->127.0.0.1:3000: i/o timeout" Aug 28 11:35:24 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:24+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:24 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:24+02:00" level=debug msg="completed challenge" Aug 28 11:35:24 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:24+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:24 streamer-hifi go-librespot[2524]: time="2026-08-28T11:35:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:24 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:24 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:27 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 28 11:35:27 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:27 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:27 streamer-hifi go-librespot[2540]: go-librespot daemon starting... Aug 28 11:35:27 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:27+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:27 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:27+02:00" level=debug msg="app state loaded" Aug 28 11:35:27 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=info msg="zeroconf server listening on port 43671" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="obtained new client token: AAEsCFGE790K+i2n/u9HCqDWO8vcZnLGNQ3LL+mV6FwDYLmVGJCblSPiw/zQ0gx1WFRZAMPmKPzVZmQh6VlckmY71YOSVo20GhN8HkRuWQWclxHHowjEUqxryxRJx7jQMLTuHI4cxXpAQM8tfjcGlpN+3soeqLHYytT/nV6v3Auo7/VA8lV0WKMnTcqOOKfdnNniQ2EHxbFGYFi1edqNonj/24js0EdznoRMLJopdxOrEU4LM3IOobyx" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=debug msg="completed challenge" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:28 streamer-hifi go-librespot[2541]: time="2026-08-28T11:35:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:28 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:28 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:32 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 28 11:35:32 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:32 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:32 streamer-hifi go-librespot[2550]: go-librespot daemon starting... Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=debug msg="app state loaded" Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:35:32 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:32+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=info msg="zeroconf server listening on port 40251" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=debug msg="obtained new client token: AAGW/J6UxhxFvxJG7XkCF92u5Wee6Vc2cvzpQmXVwv/6rEUznIu0EiYFLsxyNUpuCo07TAAFdux5ZAqB7gKuxfwibLI1t97Vr+veWoWg9cQmB9fT9J9zt0pt6w8pV0FfJEMTEWqBgZRxCfSBTOgpOeV/u2Vb5wKJ36Ci90agoC+sCv+im5Q1y44Y7QJXw+xGRTx+7Xy55u5rQbq5zEEEOVb2bMF5glsU1Ow1llUaBUk3Sy6cwhPEIkuC" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=debug msg="completed challenge" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:33 streamer-hifi go-librespot[2551]: time="2026-08-28T11:35:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:33 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:33 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:34 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 28 11:35:34 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 28 11:35:34 streamer-hifi volumio[1994]: info: Streaming services startup Aug 28 11:35:34 streamer-hifi volumio[1994]: info: Starting Streaming Daemon Aug 28 11:35:34 streamer-hifi sudo[2575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 11:35:34 streamer-hifi sudo[2575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:35 streamer-hifi volumio5-onboarding[1597]: time=2026-08-28T11:35:35.012+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:55402->127.0.0.1:3000: i/o timeout" Aug 28 11:35:35 streamer-hifi volumio[1994]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 28 11:35:35 streamer-hifi sudo[2575]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:35 streamer-hifi volumio[1994]: info: Initializing connection to go-librespot Websocket Aug 28 11:35:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:35:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 11:35:36 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 11:35:36 streamer-hifi volumio[1994]: Upnp client error: Error: This socket has been ended by the other party Aug 28 11:35:36 streamer-hifi volumio[1994]: error: Cannot start Volumio Streaming Daemon Aug 28 11:35:36 streamer-hifi volumio[1994]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 11:35:36 streamer-hifi volumio[1994]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 11:35:36 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 28 11:35:36 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:36 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:36 streamer-hifi volumio[1994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:35:36 streamer-hifi go-librespot[2584]: go-librespot daemon starting... Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=debug msg="app state loaded" Aug 28 11:35:37 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:35:37] [connect] Successful connection Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=info msg="zeroconf server listening on port 39941" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:37 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:37+02:00" level=debug msg="obtained new client token: AAGOCKXMtmUFkeJmEI6hgbNb4k+rbmfCTkZU9sxvh93TNhWuTTztzbA6l0a6IbnCL5lS3aJGeJFUMelaxu8+hQ52qWqM21Xxs3PdCtxZgSPvQcaAsK3OBkWQG1eGfEGrAIq7HeYdtMFAiEVy+ufZbpnpejcSV2jW4Ply+5aybU2Lk73zjoxOhjkT/nNYHNInGhDQXxfEvjl7fq+ZU//Sf006GbSfcwKPCSB1ZrCoyjE7D9CMR2v0syLW" Aug 28 11:35:38 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:38 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:38+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:38 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:38+02:00" level=debug msg="completed challenge" Aug 28 11:35:38 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:38+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:38 streamer-hifi go-librespot[2585]: time="2026-08-28T11:35:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:38 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:38 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 11:35:38 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:35:40 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 28 11:35:40 streamer-hifi volumio[1994]: info: Initializing connection to go-librespot Websocket Aug 28 11:35:40 streamer-hifi upmpdcli[2595]: writing RSA key Aug 28 11:35:41 streamer-hifi volumio[1994]: 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 28 11:35:41 streamer-hifi volumio-remote-updater[713]: [2026-08-28 11:35:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787909737 101 Aug 28 11:35:41 streamer-hifi volumio[1994]: 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: 3 Aug 28 11:35:41 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 28 11:35:41 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:41 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:41 streamer-hifi go-librespot[2601]: go-librespot daemon starting... Aug 28 11:35:41 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:41+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:41 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:41+02:00" level=debug msg="app state loaded" Aug 28 11:35:41 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:41 streamer-hifi volumio-remote-updater[713]: Test mode disabled Aug 28 11:35:41 streamer-hifi volumio-remote-updater[713]: Alpha mode disabled Aug 28 11:35:41 streamer-hifi volumio-remote-updater[713]: Alpha legacy test mode disabled Aug 28 11:35:41 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=info msg="zeroconf server listening on port 36395" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:42 streamer-hifi volumio[1994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="obtained new client token: AAG+/AhNg1mTm5nsA5pm7QHuDONTMRcaXeCMI9RDwNLMhrFLDDUuK126nIlULOkgV1vi4ZUWiSCmdvedyJeA4zwuAHPrqoVMV4r0INIRWNE0y8gVMzxUuwiqCCtyOgb2tyA0HT7ARLZdevT1Lw5xU8a/ud2nNlj4Igd3qhKCvemvmJBwQ63uLivaEtEbc5C5+JwpOsDby2zOfVApS+tjUQVmH6X8qlQjJ2f9J1RGi1rywENDnE6rX4es" Aug 28 11:35:42 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=debug msg="completed challenge" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:42 streamer-hifi go-librespot[2602]: time="2026-08-28T11:35:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:42 streamer-hifi volumio[1994]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 28 11:35:42 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:42 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:42 streamer-hifi volumio[1994]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 11:35:42 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 11:35:42 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 28 11:35:43 streamer-hifi volumio[1994]: SPOTIFY: User informations: {"account_id":"yj9KCKFxCd","country":"FR","display_name":"Jc","email":"jc_gimenez@hotmail.fr","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31wjyuag6ibjw3klnahzxgjityq4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31wjyuag6ibjw3klnahzxgjityq4","id":"31wjyuag6ibjw3klnahzxgjityq4","images":[],"product":"premium","type":"user","uri":"spotify:user:31wjyuag6ibjw3klnahzxgjityq4"} Aug 28 11:35:43 streamer-hifi volumio[1994]: info: Spotify Successfully logged in Aug 28 11:35:43 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 11:35:43 streamer-hifi volumio[1994]: info: [1787909743428] CoreMusicLibrary::Adding element Spotify Aug 28 11:35:43 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:35:43 streamer-hifi volumio[1994]: Cannot find translation for source Mother Earth Radio Aug 28 11:35:43 streamer-hifi volumio[1994]: Cannot find translation for source Spotify Aug 28 11:35:44 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:45 streamer-hifi volumio[1994]: info: Initializing connection to go-librespot Websocket Aug 28 11:35:46 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 28 11:35:46 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:46 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:46 streamer-hifi go-librespot[2626]: go-librespot daemon starting... Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=debug msg="app state loaded" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=info msg="zeroconf server listening on port 42547" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:46 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:46+02:00" level=debug msg="obtained new client token: AAFNgx3nguN+6ZrqpOBpah0uSz9OAU0UUw5wOJHNCQk1WUkOz2JKCOYS6ELyapytxUpm9OrqfiXtRMRr33wGJvh99UWVj8fgINWQ45K3rovgcyPlKF+0Fr1k7kGWg87qV15hFqdH5LPWfjps6g0rMZE5QOLSx/kntcCkBY+cb/IuUAnEwykVOAFxJWxR9eA1sxzCEbGJLIV10nNJdQNdnKkt1AUoy+kZGJ70Oz1IS7cQE9hrUHrIST4p" Aug 28 11:35:47 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:47+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:47 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:47+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:47 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:47+02:00" level=debug msg="completed challenge" Aug 28 11:35:47 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:47+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:47 streamer-hifi go-librespot[2627]: time="2026-08-28T11:35:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:47 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:47 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:47 streamer-hifi volumio[1994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:35:47 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:47 streamer-hifi volumio[1994]: info: CoreCommandRouter::volumioGetState Aug 28 11:35:47 streamer-hifi volumio[1994]: info: CorePlayQueue::getTrack 0 Aug 28 11:35:47 streamer-hifi volumio[1994]: info: MyVolumio token set successfully Aug 28 11:35:47 streamer-hifi volumio[1994]: info: MYVOLUMIO: Adding device Aug 28 11:35:47 streamer-hifi volumio[1994]: info: MYVOLUMIO: Evaluating Server Aug 28 11:35:50 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:50 streamer-hifi volumio[1994]: info: MyVolumio status changed Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Streaming services startup Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Starting Streaming Daemon Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Removing browser output: myVolumio user plan is not superstar Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Removing audio output: Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Stoppping Tunnel 1 Aug 28 11:35:50 streamer-hifi sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 11:35:50 streamer-hifi sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:50 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 28 11:35:50 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:50 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:50 streamer-hifi go-librespot[2661]: go-librespot daemon starting... Aug 28 11:35:50 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:50+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:50 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:50+02:00" level=debug msg="app state loaded" Aug 28 11:35:50 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:50 streamer-hifi sudo[2659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 28 11:35:50 streamer-hifi sudo[2659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 11:35:50 streamer-hifi volumio[1994]: info: Setting Geolocation for MyVolumio to eu4 Aug 28 11:35:50 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:50 streamer-hifi sudo[2657]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:50 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:50 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:51 streamer-hifi volumio[1994]: info: Initializing connection to go-librespot Websocket Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi 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. Aug 28 11:35:51 streamer-hifi sudo[2659]: pam_unix(sudo:session): session closed for user root Aug 28 11:35:51 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=info msg="zeroconf server listening on port 34989" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:51 streamer-hifi volumio[1994]: error: Cannot start Volumio Streaming Daemon Aug 28 11:35:51 streamer-hifi volumio[1994]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 11:35:51 streamer-hifi volumio[1994]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 11:35:51 streamer-hifi volumio[1994]: info: Successfully Added MyVolumio device Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="obtained new client token: AAEYkMxzQxaE8mvyT1pmQ54uSFS0AMqFeOCwnDdyjM0EI94BaAdOdJGOgmnmFUHzCzHgIBuJVqZmVncVg3aBMXhU1H+0sG3wPjKtLtpDOAVSVoG0oT/u2jzOpzHCtpble4w8gnNLv2QqD4YtKOhut2zk8cmj4D23ItvOFhHBhrxUyexV9lrHeg7fQQWsNULQS+foCNeHmmM+H1UOdw0QSo8cYWT5Qoc6xymaEolHscZ1rQR/gCs7xv5p" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="new websocket client" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:51 streamer-hifi volumio[1994]: info: Remote SSH Stopped Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=debug msg="completed challenge" Aug 28 11:35:51 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:51+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:51 streamer-hifi volumio[1994]: info: Connection to go-librespot Websocket established Aug 28 11:35:52 streamer-hifi go-librespot[2662]: time="2026-08-28T11:35:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:52 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:52 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: info: Updating MyVolumio device info Aug 28 11:35:52 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:52 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:52 streamer-hifi volumio[1994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 11:35:52 streamer-hifi volumio[1994]: info: Connection to go-librespot Websocket closed Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:52 streamer-hifi volumio[1994]: info: Successfully Updated MyVolumio device Aug 28 11:35:53 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:53 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:54 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:54 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:54 streamer-hifi volumio[1994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 28 11:35:55 streamer-hifi volumio[1994]: info: Getting Spotify volume Aug 28 11:35:55 streamer-hifi volumio[1994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:35:55 streamer-hifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 28 11:35:55 streamer-hifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:55 streamer-hifi volumio[1994]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:35:55 streamer-hifi volumio[1994]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 28 11:35:55 streamer-hifi volumio[1994]: errno: -111, Aug 28 11:35:55 streamer-hifi volumio[1994]: code: 'ECONNREFUSED', Aug 28 11:35:55 streamer-hifi volumio[1994]: syscall: 'connect', Aug 28 11:35:55 streamer-hifi volumio[1994]: address: '127.0.0.1', Aug 28 11:35:55 streamer-hifi volumio[1994]: port: 9879, Aug 28 11:35:55 streamer-hifi volumio[1994]: response: undefined Aug 28 11:35:55 streamer-hifi volumio[1994]: } Aug 28 11:35:55 streamer-hifi volumio[1994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:35:55 streamer-hifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 11:35:55 streamer-hifi go-librespot[2686]: go-librespot daemon starting... Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=debug msg="app state loaded" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:35:55 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:55+02:00" level=info msg="zeroconf server listening on port 33427" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=debug msg="obtained new client token: AAF2Ji402EnSltdGFnDLr2Sb3zXiNS90cVwh3vftqXIO5f2GJ/yRJjSdOugOVIY3zYetj2+M9ICVIJj7v8hp9kfiwN+Bp3ckVxDX8nE3dI0fiSpUVUGu7wr9Opfp/RrtxEldEdteVFVcUlNXcVd/lVq4diYMUAjpjAsF8E80e4WE78KEj9h2YVef1jXmgAHHUI3M9KJhrsWKwoS9rCRxlELFS2NO02pmb55FfYOGy6FuYWcZtGfhnw==" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=debug msg="completed keyexchange" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=debug msg="completed challenge" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=info msg="authenticated AP" username="31************************q4" Aug 28 11:35:56 streamer-hifi go-librespot[2690]: time="2026-08-28T11:35:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:35:56 streamer-hifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:35:56 streamer-hifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:35:56 streamer-hifi sudo[2710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 11:34' Aug 28 11:35:56 streamer-hifi sudo[2710]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"