May 22 17:55:00 volumio volumio[2017]: info: Checking if install.sh is present May 22 17:55:00 volumio volumio[2017]: info: Executing install.sh May 22 17:55:00 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:00 volumio volumio[2017]: info: Listing playlists May 22 17:55:00 volumio volumio[2017]: info: Listing playlists May 22 17:55:00 volumio sudo[3151]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh May 22 17:55:00 volumio sudo[3151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:00 volumio volumio[2017]: info: Installing Go-librespot May 22 17:55:00 volumio volumio[2017]: info: Checking old vollibrespot installs May 22 17:55:00 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 22 17:55:00 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.39' (uid=0 pid=3162 comm="timedatectl show --property=NTPSynchronized --valu") May 22 17:55:00 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 22 17:55:00 volumio systemd[1]: Reloading. May 22 17:55:00 volumio dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.timedate1' May 22 17:55:00 volumio setdatetime-helper.sh[3160]: Time is not synchronized. Attempting to sync... May 22 17:55:00 volumio sudo[3187]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 22 May 2025 15:55:00 GMT#015' May 22 17:55:00 volumio sudo[3187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 22 17:55:00 volumio setdatetime-helper.sh[3188]: Thu May 22 17:55:00 CEST 2025 May 22 17:55:00 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:00 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:00 volumio sudo[3187]: pam_unix(sudo:session): session closed for user root May 22 17:55:00 volumio setdatetime-helper.sh[3160]: Time synchronized successfully. May 22 17:55:00 volumio systemd-journald[330]: Time jumped backwards, rotating. May 22 17:55:00 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 22 17:55:00 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 22 17:55:00 volumio volumio[2017]: info: Downloading daemon May 22 17:55:00 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 22 17:55:01 volumio volumio[2017]: info: Creating directories May 22 17:55:01 volumio volumio[2017]: info: Creating data directory May 22 17:55:01 volumio volumio[2017]: info: Creating Start Script May 22 17:55:02 volumio systemd[1]: Reloading. May 22 17:55:02 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:02 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:02 volumio sudo[3151]: pam_unix(sudo:session): session closed for user root May 22 17:55:02 volumio volumio[2017]: Plugin install end detected on script May 22 17:55:02 volumio volumio[2017]: info: Install script completed May 22 17:55:02 volumio volumio[2017]: info: Adding reference to registry May 22 17:55:02 volumio volumio[2017]: info: Done installing plugin. May 22 17:55:02 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:55:02 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 17:55:02 volumio volumio[2017]: info: Folder /tmp/plugins removed May 22 17:55:02 volumio volumio[2017]: info: Folder /tmp/downloaded_plugin.zip removed May 22 17:55:02 volumio volumio[2017]: info: Folder /data/temp removed May 22 17:55:06 volumio volumio[2017]: info: Enabling plugin spop May 22 17:55:06 volumio volumio[2017]: info: Loading plugin "spop"... May 22 17:55:09 volumio volumio[2017]: info: PLUGIN START: spop May 22 17:55:09 volumio volumio[2017]: info: Creating Spotify config file May 22 17:55:09 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:55:09 volumio volumio[2017]: info: Done. May 22 17:55:09 volumio volumio[2017]: Upnp client error: Error: This socket has been ended by the other party May 22 17:55:09 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:09 volumio volumio[2017]: info: Spotify config file written May 22 17:55:09 volumio volumio[2017]: info: No need to fix Spotify hosts May 22 17:55:09 volumio sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 22 17:55:09 volumio sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:09 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:09 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 17:55:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:09 volumio go-librespot[3251]: go-librespot daemon starting... May 22 17:55:09 volumio sudo[3249]: pam_unix(sudo:session): session closed for user root May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="no app state found" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="stored credentials not found" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=info msg="generated new device id: 1e5250ccb97116a594cc23a24e45b61fb9f88689" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:09 volumio volumio[2017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 22 17:55:09 volumio volumio[2017]: SPOTIFY: BQAALbfpRTMZ_A-X7WOpEgp5yq9fBckNsoDbQ9a7nZ6IRzsUq_iY6XW0cmJf8N8wLYItzw_A_5NO4kJYSNKO1hKkcgSgZrMJBemokRZ_gVYa4WfNDALNIjIPTvGawSvP3-j0WcsHIyICZ1utXamZJWI1qAwW76PoPp5C9IIqzyE_F-X_EzJJVMjYTe7Xaugeuc5uIAO6Y2cd9qkJCmCRoI3j5A0WQetggoezHP9OZaQ4BG_k_igyUTpSq4p0KbMCfCjiqub46wR2aI_oZpoePDg May 22 17:55:09 volumio volumio[2017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 22 17:55:09 volumio volumio[2017]: info: New Spotify access token = BQAALbfpRTMZ_A-X7WOpEgp5yq9fBckNsoDbQ9a7nZ6IRzsUq_iY6XW0cmJf8N8wLYItzw_A_5NO4kJYSNKO1hKkcgSgZrMJBemokRZ_gVYa4WfNDALNIjIPTvGawSvP3-j0WcsHIyICZ1utXamZJWI1qAwW76PoPp5C9IIqzyE_F-X_EzJJVMjYTe7Xaugeuc5uIAO6Y2cd9qkJCmCRoI3j5A0WQetggoezHP9OZaQ4BG_k_igyUTpSq4p0KbMCfCjiqub46wR2aI_oZpoePDg May 22 17:55:09 volumio volumio[2017]: info: Spotify credentials grant success - running version from March 24, 2019 May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=info msg="zeroconf server listening on port 40149" May 22 17:55:09 volumio volumio[2017]: SPOTIFY: User informations: {"country":"NL","display_name":"stolsel","email":"jan@harf.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/stolsel"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/stolsel","id":"stolsel","images":[],"product":"premium","type":"user","uri":"spotify:user:stolsel"} May 22 17:55:09 volumio volumio[2017]: info: Spotify Successfully logged in May 22 17:55:09 volumio volumio[2017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:55:09 volumio volumio[2017]: info: [1747929309680] CoreMusicLibrary::Adding element Spotify May 22 17:55:09 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:55:09 volumio volumio[2017]: Cannot find translation for source YouTube2 May 22 17:55:09 volumio volumio[2017]: Cannot find translation for source Spotify May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="obtained new client token: AABX/drZpJEU3DBQHqhwIySmRRAfi7zollF+NO+hPXqnfpvkOQClwE0nsmBPrPOCzIR1f5v1IJYccn+UQNJ0avDDa8z4PxW7q6blZf+Q7pL3aoJgmJgz5aO/+wOG6/9650GNfK1WCA41DTdpdqNE62/0WS0Q1qovBYuOPYC/pVnPKi5XjQx0phYmzu8d6YotdGAGdULv1rseVdE3Rv9fnrz4FNF/j3IiIr0QawomY1y8aDhzzmPbzyDSzhx1" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="completed keyexchange" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=debug msg="completed challenge" May 22 17:55:09 volumio go-librespot[3252]: time="2025-05-22T17:55:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:12 volumio volumio[2017]: info: go-librespot daemon successfully initialized May 22 17:55:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 22 17:55:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:13 volumio go-librespot[3265]: go-librespot daemon starting... May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="app state loaded" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="stored credentials not found" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=info msg="zeroconf server listening on port 34197" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="obtained new client token: AAACeS8vqESrKuWChFiPMki7n3n3yd4EtJG/14MDxuE4fvhyAXHlU9G/i+jebbxOsBcWfZip9IWe/Q+BwoDRrOUgaT0dJyLzuFVNOJQCedezSVlAO6YM8DOHOclprODFGULkZR4NKtKwGHSJtr8Har0mglO26+1BSzwM6gQZrdoDnLVhm6otTe8pSfh/cRvY9fcO/DlR9kvC+6yz9BSd9KrukryDWO/FaSfFdI4UVQaQrdhb0/woGbhcXlcO" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="completed keyexchange" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=debug msg="completed challenge" May 22 17:55:13 volumio go-librespot[3266]: time="2025-05-22T17:55:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:15 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:15 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:15 volumio volumio[2017]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:55:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 22 17:55:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:16 volumio go-librespot[3295]: go-librespot daemon starting... May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="app state loaded" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="stored credentials not found" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=info msg="zeroconf server listening on port 43169" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="obtained new client token: AABEA5FfFHXE2eBVcME5iNkUyzNJZwvPSoufHnn2sGiIVykZZGGevcODrECu2gedIOn2eZxUD63dZEsZZqTwM6ucgyhJ/UqCjw1Ykf5/dLFzHapbn+E2TAuMgOR8sKHpJDAYQtUJ65h7Oyg/f3ZTG2jQGlGA3sL7E1gJufJ9YMHkWDps6rctwe0Ki8oBVFMwKNGzulrfOrI8BAY39PPM+A7StzUmnF5i5pv2yyx6e3s9/X5FPDV2agKZoIBT" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="completed keyexchange" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=debug msg="completed challenge" May 22 17:55:16 volumio go-librespot[3296]: time="2025-05-22T17:55:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:17 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:17 volumio volumio[2017]: info: Listing playlists May 22 17:55:17 volumio volumio[2017]: info: Listing playlists May 22 17:55:18 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:18 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 22 17:55:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:20 volumio go-librespot[3309]: go-librespot daemon starting... May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="app state loaded" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="stored credentials not found" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=info msg="zeroconf server listening on port 39713" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="obtained new client token: AACQ0lDkT4lo2kiLmnhiBd7dTpmIT4U7JdDpaYpfZsyYJfGnfCUTbBw3AyAqTlMSHqjCoq8v5oEdfRwzNSQL864vRJsjAz3VCYa7vYPZe90ofwvgEhldhr4B0Z5Db/sIQRvIyvWzcFFK1PuohikRAnvCGCiaZQBEKx4PrSbp7cIyxC6OUL5fmlz6ooBVSYO7+RtJPyKp5SxGa6D9eK5l18qy1Rec3xhY5FHDU9x3tnbN/c58dguqYtR11MVR" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="completed keyexchange" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=debug msg="completed challenge" May 22 17:55:20 volumio go-librespot[3310]: time="2025-05-22T17:55:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:21 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:21 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 22 17:55:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:23 volumio go-librespot[3325]: go-librespot daemon starting... May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="app state loaded" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="stored credentials not found" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=info msg="zeroconf server listening on port 34981" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="obtained new client token: AABhel4/TByxKQjFFK0RF87oVMgs4MwPn3/e5z4TmeBSvDg0TMSxrsMqntJXU381Os4QxMmuDC5cHwOTDH1Gdh+PWcexJ+V0vkNeGToiS8H4PfUow7uFNMEeC8x2v7PTD6VjesGyO7z++0ZNEsoPtk6FChxCohOdGlnp67mNCw+vxOL8FqHrvLFPTlmrs/86RUw8PXQnXC0t89ckbXLv9jxRWObjnwKAsJfLAdKWa6KNOY8wZYR185NKCA==" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="completed keyexchange" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=debug msg="completed challenge" May 22 17:55:23 volumio go-librespot[3326]: time="2025-05-22T17:55:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:24 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:24 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. May 22 17:55:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:27 volumio go-librespot[3353]: go-librespot daemon starting... May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="app state loaded" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="stored credentials not found" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=info msg="zeroconf server listening on port 33321" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="obtained new client token: AABF5QIoKPU/+apdLGQ5c/aOJgRDoYF2KDkilqOKRfiISNA2+iPKm2K82BeN1U+mLGBe7JiyxT8f4ZemY3z/qLWhibQgsq03sGj30jMHuIR+PDBgK2uoaOkf24IemfGwLLXw3ANNbkLXYy26RzQy4gP0r1BoIK0uuT9ZrfnXvEb4CeIy7QwG6dgBNf5NVfHFx6dY9uXu0aH9xZ+4UGY6zCaaDZonMaUBMh5jtn88YxwPySZtu6CK7QMEIfwV" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="completed keyexchange" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=debug msg="completed challenge" May 22 17:55:27 volumio go-librespot[3354]: time="2025-05-22T17:55:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:27 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:27 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:27 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:29 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:29 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 22 17:55:30 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:30 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. May 22 17:55:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:30 volumio go-librespot[3369]: go-librespot daemon starting... May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="app state loaded" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="stored credentials not found" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=info msg="zeroconf server listening on port 38273" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="obtained new client token: AACWRXQlhNpudcjOLy1rsEVvqocgqIeaJo51LnAZWGOeCFcaIZHPPyiedZBM+peKxlq03QsTRtMiQeWcjVpozv5p3MozeoDkQ0z2I6wvb8iA3x+sYZfsn83wFpLF52+M1BSS3yWaBg5vpM2ge9CdVG8O2tD9tAr6EhW65pPDAabd1tRhQ+8Y+2+sCcKval+aBTTcDqDhXgbAlAiASjV1pmOCLL8lBgZrJ2Y9NAqE/09yOcKund2ozxSgG0nG" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="completed keyexchange" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=debug msg="completed challenge" May 22 17:55:30 volumio go-librespot[3371]: time="2025-05-22T17:55:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:33 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:33 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. May 22 17:55:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:34 volumio go-librespot[3387]: go-librespot daemon starting... May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="app state loaded" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="stored credentials not found" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=info msg="zeroconf server listening on port 41947" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="obtained new client token: AAC41fYvmntBoZEe1OAgZWwlq/HThlFlMQJ4th2PDHUo/4oPjq3+o931kaQ6T7bywb1n8lI+8ekv7C9xjALfMAw8bULz5+iDmHOiUz3wh+1MKnwBXh21JvhUJXivTX/30tiPY6SVGEKusd5wUE4ciesWq4z4NAa/JgeMsdKAv9QGNbEezTsHWI0UPmMoEspMpi1gU8LBdydgP/MJh25PIp11w91q+9Z8nxitg+S+oC4FB418le6yx1nYAfIM" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="completed keyexchange" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=debug msg="completed challenge" May 22 17:55:34 volumio go-librespot[3388]: time="2025-05-22T17:55:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 22 17:55:35 volumio volumio[2017]: info: Received Get System Version May 22 17:55:35 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:55:36 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:36 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 22 17:55:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:37 volumio go-librespot[3435]: go-librespot daemon starting... May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="app state loaded" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="stored credentials not found" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=info msg="zeroconf server listening on port 37903" May 22 17:55:37 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:37 volumio volumio[2017]: info: Listing playlists May 22 17:55:37 volumio volumio[2017]: info: Listing playlists May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="obtained new client token: AADHTE6be5Ix8rVd8kd7RNBOe4eacEu1YwEP0Vafek5jaQ2Te3tCq9V2khyX4VZcAQWTyEimh6JkytgjDHnkbSYcByTV0maALby5qg4536Qziie9Jxnl+bS0Gy7gy/dl/V/MEdZX/DkZUsY4nsH+GZEb4eKPtpPIxbaNLRTsJt/BVOjGGUgH35Xw+azWD9RmVI2x4HjZyr9Em8BEt4KOHYirIAjTq4Cn/ZHadZbKg5ku0zKl3YNleAbXqaBg" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="completed keyexchange" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=debug msg="completed challenge" May 22 17:55:37 volumio go-librespot[3436]: time="2025-05-22T17:55:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:39 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:39 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 22 17:55:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:41 volumio go-librespot[3451]: go-librespot daemon starting... May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="app state loaded" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="stored credentials not found" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=info msg="zeroconf server listening on port 38863" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="obtained new client token: AAB09xNjVlHslArATgF97DGl/08R4n/ZVFaGiVLo2WySjt4NsPCjKZCCcIy5ldYrSv42/AJKr/2qIdKNGm/KP5hYIYAe5bLaVDSJ8WPtBHG4R6gbfYMKNYyPePxuoI/4mzOQZ2L7dyZOACUhwsw7b81eHp2i79ctW51pfuMJkH/z5ErxNO4RP/k5/j42/36qZ2FgzBmi+tE4lFiSSwsI33JR+j8xcA7gk/RE1vScRNnHTzcjXK3O/agaqJa6" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="completed keyexchange" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=debug msg="completed challenge" May 22 17:55:41 volumio go-librespot[3452]: time="2025-05-22T17:55:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:42 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:42 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. May 22 17:55:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:44 volumio go-librespot[3465]: go-librespot daemon starting... May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="app state loaded" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="stored credentials not found" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=info msg="zeroconf server listening on port 43151" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="obtained new client token: AAABHD2yS4VE764NENypiY+9jIJRsBkff5ux9HArp7JdA6C0YibUYkF7i5sMI8jj4ftxrsV80MBSAadVcex39B36JacpcSQ40Pm6NmJ5IkBbdPFi5hAy2ysFZVlFtQ4y7tl9p9i6eI4sKV75+ekLuc4ZsGodBXo5Dtu+JuY8mGsUIQWM8Q2BPW7u4n8gvOJ5YHiuUsu3fUr1qNPKlgaspRYJVJ/B/Xk4ujC5gn1Ykc2izOoEXGKeSM2aYuvN" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="completed keyexchange" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=debug msg="completed challenge" May 22 17:55:44 volumio go-librespot[3466]: time="2025-05-22T17:55:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:45 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:45 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:47 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. May 22 17:55:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:48 volumio go-librespot[3495]: go-librespot daemon starting... May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="app state loaded" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="stored credentials not found" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=info msg="zeroconf server listening on port 38897" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="obtained new client token: AACVxGY4asq5zpiQs+iJM027qlKu5cHfq393LG8s75vfjHPHaedjhIURqKwhoCDxFomi3FEvunoQEZijycwETLfcWl9OgEVWQELSINUIru3/EMmdRkc/gq/VVWkTuan+sjRvQ4XiB1uy1IqMuY5y1gA/jqiNDzFZwG6hw90hDBm5kJJ9nLSELWnxlQZolgUchcAzqUVtZmBFRZWwB9+7KkfUvYS+1CzOsTpDIGZ/8F/wH/Uy5Q+QAdzx13XE" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="completed keyexchange" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=debug msg="completed challenge" May 22 17:55:48 volumio go-librespot[3496]: time="2025-05-22T17:55:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:48 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:48 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:49 volumio volumio[2017]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object] May 22 17:55:49 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone May 22 17:55:49 volumio volumio[2017]: info: Setting timezone to Europe/Amsterdam May 22 17:55:49 volumio sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 22 17:55:49 volumio sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:49 volumio sudo[3507]: pam_unix(sudo:session): session closed for user root May 22 17:55:49 volumio sudo[3511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 22 17:55:49 volumio sudo[3511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:49 volumio sudo[3511]: pam_unix(sudo:session): session closed for user root May 22 17:55:49 volumio sudo[3515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 22 17:55:49 volumio sudo[3515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:49 volumio sudo[3515]: pam_unix(sudo:session): session closed for user root May 22 17:55:49 volumio sudo[3519]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 22 17:55:49 volumio sudo[3519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:49 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=3520 comm="/usr/bin/timedatectl set-timezone Europe/Amsterdam") May 22 17:55:49 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 22 17:55:50 volumio dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.timedate1' May 22 17:55:50 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 22 17:55:50 volumio sudo[3519]: pam_unix(sudo:session): session closed for user root May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 22 17:55:50 volumio volumio[2017]: info: Loading i18n strings for locale en May 22 17:55:50 volumio volumio[2017]: Updating browse sources language May 22 17:55:50 volumio volumio[2017]: Cannot find translation for source YouTube2 May 22 17:55:50 volumio volumio[2017]: Cannot find translation for source Spotify May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:55:50 volumio volumio[2017]: Cannot find translation for source YouTube2 May 22 17:55:50 volumio volumio[2017]: Cannot find translation for source Spotify May 22 17:55:50 volumio sudo[3526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:55:50 volumio sudo[3526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:50 volumio sudo[3526]: pam_unix(sudo:session): session closed for user root May 22 17:55:50 volumio sudo[3528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:55:50 volumio sudo[3528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 17:55:50 volumio sudo[3528]: pam_unix(sudo:session): session closed for user root May 22 17:55:50 volumio volumio[2017]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.163 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:55:50 volumio volumio[2017]: info: Received Get System Info May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:55:50 volumio volumio[2017]: info: Discovery: Getting this device information May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:50 volumio volumio[2017]: info: Listing playlists May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:55:50 volumio volumio[2017]: info: Fetching Streaming Services browse cache May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 17:55:50 volumio volumio[2017]: info: Received Get System Version May 22 17:55:50 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:55:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. May 22 17:55:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:51 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:51 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:51 volumio go-librespot[3552]: go-librespot daemon starting... May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="app state loaded" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="stored credentials not found" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=info msg="zeroconf server listening on port 38483" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="obtained new client token: AAAvqMVB+Abq1fH+xDrGbqbhI6F98DaauFVoSTqTB7Icu0WkgmUoJ7HNLvK/2vN3PVmZ8TalSbRv9gMJYE14C2h/dJ5Cgl+uJ4sWchXdSyzr+WetIMlraStleoyIT60EW9CcsIjFDN0TbMfngIrOuWX9snLE2tPMEN4AWlBZ2n4akQ9by2G7EBzupkhwWHd9B3MFqx3e2i25DRBTdNsh9rXwSJJVXn56qOCdvPW5SoeQ0A1VvRJMYZXC4pR0" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="completed keyexchange" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=debug msg="completed challenge" May 22 17:55:51 volumio go-librespot[3553]: time="2025-05-22T17:55:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:55:52 volumio volumio[2017]: info: Received Get System Info May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:55:52 volumio volumio[2017]: info: Discovery: Getting this device information May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:52 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:55:53 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:55:53 volumio volumio[2017]: info: Received Get System Info May 22 17:55:53 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:55:53 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:55:53 volumio volumio[2017]: info: Discovery: Getting this device information May 22 17:55:53 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:53 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:55:54 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:54 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. May 22 17:55:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:55 volumio go-librespot[3566]: go-librespot daemon starting... May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="app state loaded" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="stored credentials not found" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=info msg="zeroconf server listening on port 35725" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="obtained new client token: AABRT/TxG4xnUjNRNpIrdTXlor0ExaTUtP59+LyaXnKokvYetUVEIPyX3nYrOzyjmaRUm+zbzEhnbsOXCwvGDjolNS3FUQJc79ORphS2T7ZtFMHbhqipaX9t6I6/pWOtkPfRVui+OSmmdrZzLjdJ7cL5XDurua3oTqHmaAphS2OE5imljSmriMSg1ZY0UF06ZPLWMvo+bbt8UGnnjIYoI/JSepgkfvQrsD/drsKT/uzKj47hkCM1WKHDzfoa" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="completed keyexchange" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=debug msg="completed challenge" May 22 17:55:55 volumio go-librespot[3567]: time="2025-05-22T17:55:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:55:57 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:55:57 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:55:57 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:55:57 volumio volumio[2017]: info: Listing playlists May 22 17:55:57 volumio volumio[2017]: info: Listing playlists May 22 17:55:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. May 22 17:55:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:55:58 volumio go-librespot[3596]: go-librespot daemon starting... May 22 17:55:58 volumio go-librespot[3597]: time="2025-05-22T17:55:58+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:55:58 volumio go-librespot[3597]: time="2025-05-22T17:55:58+02:00" level=debug msg="app state loaded" May 22 17:55:58 volumio go-librespot[3597]: time="2025-05-22T17:55:58+02:00" level=debug msg="stored credentials not found" May 22 17:55:58 volumio go-librespot[3597]: time="2025-05-22T17:55:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=info msg="zeroconf server listening on port 41715" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="obtained new client token: AAAXq05ySXUklamPaVHUngjZyrRFbN1MBGo/XVlk5/aY0YsFXxHGBmG2+1OHvmKhMunJXnXiPeUnOuiH5Pjv4SCsZMwOMS+Os2emOnEpcNTyPbLIQ9R1kZNq4MSVgzjEug6mgol09+13ftMLsXg8d3pFbZeK2wxc1+52U6vaV50+Rc7G0hpsiIAwcWtobl1GU8y5pbhHuAlqVUBj7pj50gMNGpm/FuwkkA2pzDYG0RMGUWrgn0FWk8eULA==" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="completed keyexchange" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=debug msg="completed challenge" May 22 17:55:59 volumio go-librespot[3597]: time="2025-05-22T17:55:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:55:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:55:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:56:00 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 17:56:00 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:56:00 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:56:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. May 22 17:56:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:02 volumio go-librespot[3612]: go-librespot daemon starting... May 22 17:56:02 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="app state loaded" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="stored credentials not found" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:56:02 volumio setdatetime-helper.sh[3614]: Time is not synchronized. Attempting to sync... May 22 17:56:02 volumio sudo[3628]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 22 May 2025 15:56:02 GMT#015' May 22 17:56:02 volumio sudo[3628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 22 17:56:02 volumio setdatetime-helper.sh[3629]: Thu May 22 17:56:02 CEST 2025 May 22 17:56:02 volumio setdatetime-helper.sh[3614]: Time synchronized successfully. May 22 17:56:02 volumio systemd-journald[330]: Time jumped backwards, rotating. May 22 17:56:02 volumio sudo[3628]: pam_unix(sudo:session): session closed for user root May 22 17:56:02 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 22 17:56:02 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=info msg="zeroconf server listening on port 36661" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="obtained new client token: AAALa6H6zoZcv/eYuVTfpAflL8NUkRHo2TksVUBKLAZZ/DGd2zPdsd0RWzRZBU5nuW2N6cGP2FK5e0aMs8NTXfIsUqMXGPhGQ8JVyiCEG4dhEdVv9u8nuJqanGAetP5MHu28qP7gsYOt8qyrOGTIRwEOOVdZczWa8oR/TBIMPiI/6L7x3KEHMO0kd5Nds5zlY4E3kEc7natjRPxKH736+nIO9RmPYW+jE33r0woZagX9BzgaaG8wEInWWLQY" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="completed keyexchange" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=debug msg="completed challenge" May 22 17:56:02 volumio go-librespot[3613]: time="2025-05-22T17:56:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:56:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:56:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:56:03 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:56:03 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:56:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. May 22 17:56:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:05 volumio go-librespot[3637]: go-librespot daemon starting... May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="app state loaded" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="stored credentials not found" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=info msg="zeroconf server listening on port 38245" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="obtained new client token: AAAsBYrHBpX2152+pcxDRqaM8gX2A6o3Re+knxzp39pffKPH6vHMB0WQB07InssXpiiro9MoNIrTsB3C9TtV/si+QZwkQxWw/vB0dR3SzqLIOWNTpZKfJgQauvFfOQitfQjW3+2dgJycTnACsVB6tINN8zp3yHotZ7ERE8acC9QdN6b1+hdzA+Egoku81CckeBWa7GqSpsja3DxXTpV59QI7hEDcaZhrAsiKz0Cb5jvhrloLRGBXTl7y1g==" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="completed keyexchange" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=debug msg="completed challenge" May 22 17:56:05 volumio go-librespot[3638]: time="2025-05-22T17:56:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:56:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:56:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:56:06 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:56:06 volumio volumio[2017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:56:07 volumio volumio[2017]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 17:56:07 volumio volumio[2017]: info: Received Get System Version May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:56:07 volumio volumio[2017]: info: Received Get System Info May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:56:07 volumio volumio[2017]: info: Discovery: Getting this device information May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::volumioGetState May 22 17:56:07 volumio volumio[2017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:56:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. May 22 17:56:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:08 volumio go-librespot[3665]: go-librespot daemon starting... May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=debug msg="app state loaded" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=debug msg="stored credentials not found" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:56:08 volumio go-librespot[3666]: time="2025-05-22T17:56:08+02:00" level=info msg="zeroconf server listening on port 42015" May 22 17:56:09 volumio volumio[2017]: info: Initializing connection to go-librespot Websocket May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=debug msg="new websocket client" May 22 17:56:09 volumio volumio[2017]: info: Connection to go-librespot Websocket established May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=debug msg="obtained new client token: AADUjQw2BWL39Q7Iq9L9hqWTUbRL4GrXpRst2lAdQ1XkvAyJw/XIHCpnV/Xw1GgC76h+Hi62fvWHhlpmnmRcCkcEQt6t/7dT1zs6W35+364tF7+hS5u1X87NnbD8s1WXkKrpCF2EZrZXsg7RmWHF8V/6lw1h5b+8ki2ylcXwF7cOrOAnHHiKaTKFcycQuGSNCyJ5OKoWkbl8+WRMy5f0K8cBaw69mCvXn8KejyCRtgJGd016rZmdhbz9/Mj7" May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=debug msg="completed keyexchange" May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=debug msg="completed challenge" May 22 17:56:09 volumio go-librespot[3666]: time="2025-05-22T17:56:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " May 22 17:56:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 22 17:56:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 22 17:56:09 volumio volumio[2017]: info: Connection to go-librespot Websocket closed May 22 17:56:12 volumio volumio[2017]: info: Getting Spotify volume May 22 17:56:12 volumio volumio[2017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:56:12 volumio volumio[2017]: Error: connect ECONNREFUSED 127.0.0.1:9879 May 22 17:56:12 volumio volumio[2017]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 22 17:56:12 volumio volumio[2017]: errno: -111, May 22 17:56:12 volumio volumio[2017]: code: 'ECONNREFUSED', May 22 17:56:12 volumio volumio[2017]: syscall: 'connect', May 22 17:56:12 volumio volumio[2017]: address: '127.0.0.1', May 22 17:56:12 volumio volumio[2017]: port: 9879, May 22 17:56:12 volumio volumio[2017]: response: undefined May 22 17:56:12 volumio volumio[2017]: } May 22 17:56:12 volumio volumio[2017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:56:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. May 22 17:56:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 22 17:56:12 volumio go-librespot[3694]: go-librespot daemon starting... May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=info msg="running go-librespot 0.2.0" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=debug msg="app state loaded" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=debug msg="stored credentials not found" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 17:56:12 volumio go-librespot[3695]: time="2025-05-22T17:56:12+02:00" level=info msg="zeroconf server listening on port 33043" May 22 17:56:12 volumio sudo[3704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-22 17:55' May 22 17:56:12 volumio sudo[3704]: 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="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"