-- Logs begin at Wed 2025-07-02 08:22:37 CEST, end at Wed 2025-07-02 08:44:17 CEST. -- Jul 02 08:43:01 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:01 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. Jul 02 08:43:03 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:03 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:03 volumio go-librespot[16565]: go-librespot daemon starting... Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=debug msg="app state loaded" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+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]" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+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]" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=info msg="zeroconf server listening on port 42397" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=debug msg="obtained new client token: AADmyvHi1fEYHgCL1PZlT3b0/fcfe9OIBwCNqi7siYRCn3RMz27TxJ0ufI83/LdKLAqIhdRAnAy5tjjF2d4XZNynL327pzH71IBL1UwwwnJ1PPd9l8THQkVfSWmvW9sWKgzs0MGl48RaVYqJtVwnpvUyEbG9VlGgRMYmzCEy8ThZHtLr4xP/BnQIb2yS6KRYZlpBCXff1T5LPVu5WVfMoosmjU2g49lWhmNuH9tL96tOJMliScSk7YY=" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=debug msg="completed challenge" Jul 02 08:43:03 volumio go-librespot[16565]: time="2025-07-02T08:43:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:04 volumio volumio[1015]: info: Reading package lists... Jul 02 08:43:04 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:04 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:05 volumio volumio[1015]: info: Building dependency tree... Jul 02 08:43:05 volumio volumio[1015]: info: Reading state information... Jul 02 08:43:05 volumio volumio[1015]: info: All packages are up to date. Jul 02 08:43:05 volumio sudo[16346]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:05 volumio sudo[16589]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jul 02 08:43:05 volumio sudo[16589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:06 volumio volumio[1015]: info: Reading package lists... Jul 02 08:43:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. Jul 02 08:43:06 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:06 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:06 volumio go-librespot[16593]: go-librespot daemon starting... Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=debug msg="app state loaded" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43: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]" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43: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]" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43: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]" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=info msg="zeroconf server listening on port 41747" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=debug msg="obtained new client token: AAD1/BSIokm21G3A6nQf4CKD7dHNC+W8fcxQBBY0K7zpeIiRkA7g+YFbzFMyaqXqyZaOkcLUwGbw0HmXe8ku9ztQ+IjvSw+f2WdWGJv+x7v/ZNtXM3ODgHUKwNZ6+s09lUohHfhbOQ3SdPh+j1c4GKAxe6Z4o/sZ7PEpHejuMyyTVOyFJB1cEVnspYwjfVB1YfqutGpDB0WFZBEGJgu8EcT3/s7javofHplTWA1a5kqOFNAtoPIG+3U=" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=debug msg="completed challenge" Jul 02 08:43:06 volumio go-librespot[16593]: time="2025-07-02T08:43:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:07 volumio volumio[1015]: info: Building dependency tree... Jul 02 08:43:07 volumio volumio[1015]: info: Reading state information... Jul 02 08:43:07 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:07 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:08 volumio volumio[1015]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jul 02 08:43:08 volumio sudo[16589]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:08 volumio volumio[1015]: info: python3-pip is already the newest version (18.1-5). Jul 02 08:43:08 volumio volumio[1015]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jul 02 08:43:08 volumio sudo[16602]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 02 08:43:08 volumio sudo[16602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:08 volumio sudo[16602]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:08 volumio volumio[1015]: info: lib/systemd/system/fusiondsp.service Jul 02 08:43:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. Jul 02 08:43:10 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:10 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:10 volumio go-librespot[16605]: go-librespot daemon starting... Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=debug msg="app state loaded" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+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]" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+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]" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+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]" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=info msg="zeroconf server listening on port 43965" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=debug msg="obtained new client token: AAD3quOwNvPb7Xt1EAmQpB4vOhnBHr7JYKeQ++ee557mYMmPFU62nBevjmMFFzy9xIbMUAgZBHTrkqyoJrD8jJM4rkSL9nFJSbrt33thgXVQBGIG1tK9gxUBVgpmCm+XePh6zcasnSTyk5shNBW5usTyFdrPckmNz9aRfCo+jUP0SL6C1SuhwWkx7TG1rNj/+JmFJl+t6GLlscGCYG9FtQmrvLNt2aOZamOEcJ8PXSFFX6M+uh1etVY=" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=debug msg="completed challenge" Jul 02 08:43:10 volumio go-librespot[16605]: time="2025-07-02T08:43:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:10 volumio volumio[1015]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jul 02 08:43:10 volumio volumio[1015]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jul 02 08:43:10 volumio volumio[1015]: info: Jul 02 08:43:10 volumio volumio[1015]: info: cgui-1.0.0.zip opened Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/LICENSE.txt Jul 02 08:43:10 volumio sudo[16615]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/README.md Jul 02 08:43:10 volumio sudo[16615]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/build/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/robots.txt Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/logo512.png Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/asset-manifest.json Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/index.html Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/.put_statics_here Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/favicon.ico Jul 02 08:43:10 volumio sudo[16615]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/logo192.png Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/manifest.json Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/css-variables.css Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/service-worker.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/build/static/ Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/build/static/css/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jul 02 08:43:10 volumio sudo[16617]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jul 02 08:43:10 volumio sudo[16617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/build/static/media/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/build/static/js/ Jul 02 08:43:10 volumio sudo[16617]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.71e11955.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jul 02 08:43:10 volumio sudo[16619]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jul 02 08:43:10 volumio sudo[16619]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/config/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/config/camillagui.yml Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/config/gui-config.yml Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/backend/ Jul 02 08:43:10 volumio sudo[16619]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/filters_test.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/version.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/filters.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/settings.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/views.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/filterdefaults.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__init__.py Jul 02 08:43:10 volumio volumio[1015]: info: creating directory: cgui/backend/__pycache__/ Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/routes.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/backend/filemanagement.py Jul 02 08:43:10 volumio volumio[1015]: info: extracting: cgui/main.py Jul 02 08:43:11 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:12 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Jul 02 08:43:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:13 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:13 volumio go-librespot[16625]: go-librespot daemon starting... Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=debug msg="app state loaded" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+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]" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+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]" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=info msg="zeroconf server listening on port 34981" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=debug msg="obtained new client token: AACmHk0iapYHhFj8s1oy3wzrUGSExzRBPBmU1h66frbcyu8Fet7EAkvVRWDWqYvEagE7cjVzLxOm1yo4pxy4HXN/K1VVXaygNRFloz1TXMx4Gkq1ZB9E2NDv+qEpUcc7LOV1ND98Gp+IcoSww7SaM3eNZpGX+fo3QvBOa71rAVLUVQdpOeyO6yR6JeYBJibnWlcdfyRhx8e1v2IQD9Y4fNmTozR6xFT4/6FSIOqYtlpLuOUosQVUdlw=" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=debug msg="completed challenge" Jul 02 08:43:13 volumio go-librespot[16625]: time="2025-07-02T08:43:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:15 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:15 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:16 volumio volumio[1015]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jul 02 08:43:16 volumio volumio[1015]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-3yx7aspq Jul 02 08:43:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Jul 02 08:43:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:17 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:17 volumio go-librespot[16654]: go-librespot daemon starting... Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=debug msg="app state loaded" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+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]" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+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]" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+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]" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=info msg="zeroconf server listening on port 40971" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=debug msg="obtained new client token: AAC75iMgwU/HPKqRtCMpqDq5cxHIMXMg7LDjEimU1+nzSoG0IZvQeY59wK+gu+HlcO5fwPgvH06eAiYm6e6RveqDimx4FObe22TxNRZU2OX5KTWR/uljAVtJGxCmDjwBI3zveu+GAFarLpWOs/ZiJzdvg2ggR97lvKEw+yybuCEqtRFJGNJXi6V4Tib3PpeCEzJ8jyIqufprgQQAnUAefGnfi8qhYAQLxTOd44GUFCvpNgIG5nTLuBQ=" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=debug msg="completed challenge" Jul 02 08:43:17 volumio go-librespot[16654]: time="2025-07-02T08:43:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:18 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:18 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:19 volumio volumio[1015]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Jul 02 08:43:19 volumio volumio[1015]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Jul 02 08:43:19 volumio volumio[1015]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Jul 02 08:43:19 volumio volumio[1015]: info: Building wheels for collected packages: camilladsp Jul 02 08:43:19 volumio volumio[1015]: info: Running setup.py bdist_wheel for camilladsp: started Jul 02 08:43:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Jul 02 08:43:20 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:20 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:20 volumio go-librespot[16679]: go-librespot daemon starting... Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=debug msg="app state loaded" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:20 volumio volumio[1015]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jul 02 08:43:20 volumio volumio[1015]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-47ywzqfh/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jul 02 08:43:20 volumio volumio[1015]: info: Successfully built camilladsp Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43: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]" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43: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]" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43: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]" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=info msg="zeroconf server listening on port 42837" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=debug msg="obtained new client token: AAB/cXMsWpyxJh37snimf0UN6DOUr36Fk9dwKh6lJgV0oO59jB2HQjRO25jJs/cVQpZy+TWR2pjPnHtPTqXfOaphXDIo1mLYupCMrPIEGgg/V6VAglkib96xpglr0VkSMk4xtowAeFPwbqal4nQ/wNpySXhRZ1HnnUZqJSECd3QS8EkS2egvkdBgmLEMURv95iUjQ9QPyrRlD0ArNwWqmATL/Zm0o13FANS5PJ90r25J8T6iRLYoRB4=" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:20 volumio go-librespot[16679]: time="2025-07-02T08:43:20+02:00" level=debug msg="completed challenge" Jul 02 08:43:21 volumio go-librespot[16679]: time="2025-07-02T08:43:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:21 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:21 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:24 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:24 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Jul 02 08:43:24 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:24 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:24 volumio go-librespot[16688]: go-librespot daemon starting... Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=debug msg="app state loaded" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+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]" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+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]" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+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]" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=info msg="zeroconf server listening on port 43965" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=debug msg="obtained new client token: AABlT6/qbjXDQYth0XCDYrTQWCgbDiUiparnvS274ogyC9dAnWSy11T31aVtheiPfL/AN0Mx4vteLHS35QccUnxK9ZWcvWg1TfH4YY2//eRQNCVE32hmFicqybPmlf3JXwxtI23O1fjpMuuTHHTEsS1mNfkHa9NLJAQZin6grhkNUc0MZAU6Zh0lrGNvmTRUG65zZQv0rdFhWXePKPpQDw6nlkqVjP/EMxlQBIh++OL7XmHsx6ix5C8=" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=debug msg="completed challenge" Jul 02 08:43:24 volumio go-librespot[16688]: time="2025-07-02T08:43:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:27 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:27 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:27 volumio volumio[1015]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jul 02 08:43:27 volumio volumio[1015]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-9qbi_e94 Jul 02 08:43:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. Jul 02 08:43:27 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:27 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:27 volumio go-librespot[16717]: go-librespot daemon starting... Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=debug msg="app state loaded" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+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]" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+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]" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+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]" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=info msg="zeroconf server listening on port 39859" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=debug msg="obtained new client token: AABm22QTzi1BACVmIg98n0EJ8+Yj6N6itLHnz9uqs4TaexjTzp4sd8aOFnuLSMnk72/B0EmnjanY4rbTCmyrgjj54m848mFDmBjLdVDQFwfLc9tl4TH9D0p8cvt3jEEcHSjkp9KIZkLfnUIsbMrp2Z0D8/+9rMCtfyYOaxTHHDKrMYcgy746iQRqX/qTkMfb0W3SyviammwodwZ+thjal1drhifAgyQ3oM5qHTibbKPs2vRw9rSiGCI=" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=debug msg="completed challenge" Jul 02 08:43:27 volumio go-librespot[16717]: time="2025-07-02T08:43:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jul 02 08:43:29 volumio volumio[1015]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jul 02 08:43:30 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:30 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:30 volumio volumio[1015]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jul 02 08:43:30 volumio volumio[1015]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jul 02 08:43:30 volumio volumio[1015]: info: Building wheels for collected packages: camilladsp-plot Jul 02 08:43:30 volumio volumio[1015]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jul 02 08:43:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 172. Jul 02 08:43:31 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:31 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:31 volumio go-librespot[16741]: go-librespot daemon starting... Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=debug msg="app state loaded" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+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]" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+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]" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+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]" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=info msg="zeroconf server listening on port 42069" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=debug msg="obtained new client token: AAD9wSOlPZQ7a2Z5dr0gahWUkFCWgVRgCSgGGJHWXVw4pXyM70U5xkzfQB+ny+greGcpceN+U4r0HLBwU5KDNvD8CELyeBUvuX5ePRKTFZdLpQCHBqCOlxg5fSWvZWVNHH2kKLurrw1ArshVawsAsVgInmyHxMSIcJ8SrNNmF4mjRwhtncSlRxsQ5C24JSH9fHi1p+6Xlh7LyIr+NwTGD4we71vYC6xPqmMHAxqbbcsOU/DBUGlqXHc=" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=debug msg="completed challenge" Jul 02 08:43:31 volumio go-librespot[16741]: time="2025-07-02T08:43:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:31 volumio volumio[1015]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jul 02 08:43:31 volumio volumio[1015]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-mf0l_ndm/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jul 02 08:43:31 volumio volumio[1015]: info: Successfully built camilladsp-plot Jul 02 08:43:32 volumio volumio[1015]: info: copying hw detection script Jul 02 08:43:32 volumio volumio[1015]: info: Detected cpu architecture as x86_64 Jul 02 08:43:33 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:33 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:34 volumio sudo[16765]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jul 02 08:43:34 volumio sudo[16765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:43:34 volumio volumio[1015]: info: Reading package lists... Jul 02 08:43:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 173. Jul 02 08:43:34 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:34 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:34 volumio go-librespot[16769]: go-librespot daemon starting... Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=debug msg="app state loaded" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43: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]" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43: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]" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43: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]" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=info msg="zeroconf server listening on port 33537" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=debug msg="obtained new client token: AADnGrosCPy6z6ipki9LzMtuzDXeLKL2vj08gSAgl90GlJOxnaNWcHiLJLlq1DlXDJps/rJtNC5QRcWFmgmruTBELsW1oVK8ySNftjF8MtrtR+lMOr9sKAz0Bd3Cnz8xcxyL8EGKqFafnxa0jE0VGHoEAR+ZKOLP1py9pDNQFnKTc5BWe3PTA/KIHe6nIm4MSJKJI25rkbVQ6bU9qcB+6lIypVxGrHEwnzfgThDk+zDUz77rW1d+Dcc=" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=debug msg="completed challenge" Jul 02 08:43:34 volumio go-librespot[16769]: time="2025-07-02T08:43:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:35 volumio volumio[1015]: info: Building dependency tree... Jul 02 08:43:35 volumio volumio[1015]: info: Reading state information... Jul 02 08:43:36 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:36 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:37 volumio volumio[1015]: info: drc is already the newest version (3.2.2~dfsg0-2). Jul 02 08:43:37 volumio sudo[16765]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:37 volumio sudo[16314]: pam_unix(sudo:session): session closed for user root Jul 02 08:43:37 volumio volumio[1015]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jul 02 08:43:37 volumio volumio[1015]: info: Install script completed Jul 02 08:43:37 volumio volumio[1015]: info: Done installing plugin. Jul 02 08:43:37 volumio volumio[1015]: info: Enabling plugin fusiondsp Jul 02 08:43:37 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 08:43:37 volumio volumio[1015]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Bandcamp Discover","name":"bandcamp","category":"music_service","version":"1.2.0","icon":"fa-bandcamp","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.3","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"1.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true}] Jul 02 08:43:37 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 02 08:43:37 volumio volumio[1015]: info: Folder /tmp/plugins removed Jul 02 08:43:37 volumio volumio[1015]: info: Folder /tmp/downloaded_plugin.zip removed Jul 02 08:43:37 volumio volumio[1015]: Plugin install end detected on script Jul 02 08:43:37 volumio volumio[1015]: info: Folder /data/temp removed Jul 02 08:43:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 174. Jul 02 08:43:38 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:38 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:38 volumio go-librespot[16791]: go-librespot daemon starting... Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=debug msg="app state loaded" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+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]" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+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]" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+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]" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=info msg="zeroconf server listening on port 35879" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=debug msg="obtained new client token: AACy1p34cTGDuzbP0PPm/CtQZxcqGYCnWxf0TnwcqupAdC/1c3zl31tH/bji4MeN0Z1rkKcWY252R839JaOoxTVdyOcXaqA0SwWHr7KpACxjmoX+qOqeMs1SfDCsCx/J5hA9pKoxWX76DM9xnlVmcz059RnvK2seYWwymTngq9WJDZZaR5Inc6u8GXsVCw19F/c1euhoRuuxbo7Z8Q1GcsTGIhHF9FCv52wXJ/0KOKygDv5EHVNdUwg=" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=debug msg="completed challenge" Jul 02 08:43:38 volumio go-librespot[16791]: time="2025-07-02T08:43:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:39 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:39 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 175. Jul 02 08:43:41 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:41 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:41 volumio go-librespot[16799]: go-librespot daemon starting... Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=debug msg="app state loaded" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+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]" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+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]" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+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]" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=info msg="zeroconf server listening on port 44831" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=debug msg="obtained new client token: AADu2lvYxHIJAf5yXw2vI7oEe/J2VCThe5iN0tRojw7RNPMj21ZdMlBNgKIs8CfL8Cfi/8NQ25B+nabA/oYqnYnGMINazOag/3bYcR5KJccY3uAsaxuTypHJEPTdiZV2nENWQGKOf2zmPdsPZoRL8WyX2MbKdYbWJRrL5NCL4PK11N4OV9dUvqpeydAYkuSNXf7j1tD8NLe6IyC2mapCdR0WiIOK4rELshkiceVWVADRf7h8mzoKSx0=" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=debug msg="completed challenge" Jul 02 08:43:41 volumio go-librespot[16799]: time="2025-07-02T08:43:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:42 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:42 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 176. Jul 02 08:43:45 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:45 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:45 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:45 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:45 volumio go-librespot[16807]: go-librespot daemon starting... Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=debug msg="app state loaded" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+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]" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+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]" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+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]" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=info msg="zeroconf server listening on port 33755" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=debug msg="obtained new client token: AAA7PMHeyE3NYLWujAoI4Ac/XRDK7qjix5B1OW2K1NJciNnkzLUIMvBgKa/oseS2JJQaCLxaXNn8IGPqrRjX81/Hv+W8zrG8DdMqzKwERQXGOlXm/ephn3JYCxyx2qFghboI8QlmvMimKn1R55cThABVymxS2oD836mGY4S3GNLNejmG1nyv+dfAm+xhDaHg1AHYVRY9IfXKerKp06WMIHMN5Yla5Vo2XMD4/pbrtfVqCiv9nfCSyYU=" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:45 volumio go-librespot[16807]: time="2025-07-02T08:43:45+02:00" level=debug msg="completed challenge" Jul 02 08:43:46 volumio go-librespot[16807]: time="2025-07-02T08:43:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:48 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:48 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 177. Jul 02 08:43:49 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:49 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:49 volumio go-librespot[16874]: go-librespot daemon starting... Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=debug msg="app state loaded" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+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]" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+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]" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+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]" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=info msg="zeroconf server listening on port 39789" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=debug msg="obtained new client token: AADRBIamVw99n2yUpe24tMaJ8r6aey3qH8ozGShyMaum+s1XAGj7aJvX6Hi4bvdse1jQfEqF3FT7bITLvlvFPvCAvLkKbfDWZOk3CdIyJ/qzsvUwRjbNyaOFHEcCDIUMpIc6qVpiS5cw7XjIs3oXqBEeZm7p28Tqp8p+Ucj4NfZZWh/4Ug65AVpQO4LJEoabVU0t4xtWd1S8IbX0buLpj0eFW9eipOwbhrISD850msmaFtqHlaxVEgs=" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=debug msg="completed challenge" Jul 02 08:43:49 volumio go-librespot[16874]: time="2025-07-02T08:43:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:51 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:51 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 178. Jul 02 08:43:52 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:52 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:52 volumio go-librespot[16885]: go-librespot daemon starting... Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=debug msg="app state loaded" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+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]" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+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]" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+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]" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=info msg="zeroconf server listening on port 34257" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=debug msg="obtained new client token: AAAIvrEDpxtZ6kujJ+f+0G3oRhK1TsuXSTxVI9wFcHtOBEJ/6XOPtzedMEYcEIOwLPs29fIUq9w/stE1VzzsJaqt2mbbTvcOXk08DeYHEQLXEBjVimEe9mBYS6D5Xk5LBJsYRHnvqricGKMl0eqj8aAh7nSMkGqHfKmGxTfVWDC2cC/+Sra6bZ0eouJI3tvqUThFBxf31oKeYI4JFOwQIcabYSxvl2Oxn1XszVkrkcQVauZAs+vd4ak=" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=debug msg="completed challenge" Jul 02 08:43:52 volumio go-librespot[16885]: time="2025-07-02T08:43:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:54 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:54 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 179. Jul 02 08:43:56 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:56 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:56 volumio go-librespot[16893]: go-librespot daemon starting... Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=debug msg="app state loaded" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+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]" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+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]" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+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]" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=info msg="zeroconf server listening on port 37523" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=debug msg="obtained new client token: AAA+6lthP4bxj2i0piZV7Hfpds/NYC55GSq8NUNLWpZ/enm/SGt0XqD11f03kXo6FTzcg5avrJvmhBbt54MzKZ+2Wt6umydaD6RypZZUSYoXY3IyhA9wqZWIabZJuJQw96PA+Cb97SHf8e6LcYrmffv95Fb361XrqXGXbjTb/6KB1Rfkj3NrRA+9bAJsPP13h8XorZwe41/WAztAFIJnOVYqJi+KhdB6BoVwsAKkEbHhRRNhns+eYL0=" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=debug msg="completed challenge" Jul 02 08:43:56 volumio go-librespot[16893]: time="2025-07-02T08:43:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:43:57 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:43:57 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:43:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:43:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 180. Jul 02 08:43:59 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:43:59 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:43:59 volumio go-librespot[16915]: go-librespot daemon starting... Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=debug msg="app state loaded" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+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]" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+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]" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=info msg="zeroconf server listening on port 44249" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=debug msg="obtained new client token: AADbM2SsFZCAP1b/wfJ2Yu4JUodjFoYSonq4NdqPV2iUDfooY9mVKM+wE7dMeZWoGdxuTugzRHiVdl90CTemFYKwoYoua1FwJ9RfqsaaM14mpsP0OrWjlfWaqQiKDwRl2oX5JLkWdzzEKjQOE1oYSqeEbayK6NyAITYFmleprIPPMRrhOnnJsaVClDfxr3ugNxZXLPfqQ5Kfe1raHmfZKQ7lsi3erMJmTfqf3uCJsGw0alrcAcW42IE=" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=debug msg="completed keyexchange" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=debug msg="completed challenge" Jul 02 08:43:59 volumio go-librespot[16915]: time="2025-07-02T08:43:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:43:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:43:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:00 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:00 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:00 volumio volumio[1015]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/calmradio/volumio/buster/amd64 Jul 02 08:44:02 volumio volumio[1015]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/calmradio/volumio/buster/amd64 Jul 02 08:44:02 volumio volumio[1015]: info: CoreCommandRouter::volumioRemoveToBrowseSourcescalmradio Jul 02 08:44:02 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source Bandcamp Discover Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source Calm Radio Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source SoundCloud Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source YouTube Music Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source LastFM Jul 02 08:44:02 volumio volumio[1015]: Cannot find translation for source Randomizer Jul 02 08:44:02 volumio volumio[1015]: info: Check plugin dependencies Jul 02 08:44:02 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 08:44:03 volumio volumio[1015]: info: Rename folder Jul 02 08:44:03 volumio volumio[1015]: info: Folder /tmp/downloaded_plugin.zip removed Jul 02 08:44:03 volumio volumio[1015]: info: Move to category Jul 02 08:44:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:44:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 181. Jul 02 08:44:03 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:44:03 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:44:03 volumio go-librespot[16932]: go-librespot daemon starting... Jul 02 08:44:03 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="app state loaded" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+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]" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+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]" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=info msg="zeroconf server listening on port 34639" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="new websocket client" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="obtained new client token: AAAtligIrY83/mU8ptKIrDdZ11Jvo4aImfNIgu4xat3+XwV3RgYTuFmGVEwgSInawhyCQKfavD8GhZm15k/vh2SIdfQPqx/pOPOs1PnDT/UE2/qLh42q+7JVQDQvoj8fU/xahE16uAvoiu27wnlL/umVehtEc+yG7iakihshxxKwZdQ3lHk+UcfwomJKdzRPD0sFZgS4ANVVjWjkoHHMuu83ImIEUCie1fop3Q70xK+PCytF53QgZas=" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:44:03 volumio volumio[1015]: info: Connection to go-librespot Websocket established Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="completed keyexchange" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=debug msg="completed challenge" Jul 02 08:44:03 volumio go-librespot[16932]: time="2025-07-02T08:44:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:44:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:44:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:03 volumio volumio[1015]: info: Connection to go-librespot Websocket closed Jul 02 08:44:03 volumio volumio[1015]: info: Checking if install.sh is present Jul 02 08:44:03 volumio volumio[1015]: info: Executing install.sh Jul 02 08:44:03 volumio sudo[16947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/calmradio/install.sh Jul 02 08:44:03 volumio sudo[16947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:44:03 volumio sudo[16947]: pam_unix(sudo:session): session closed for user root Jul 02 08:44:03 volumio volumio[1015]: Plugin install end detected on script Jul 02 08:44:03 volumio volumio[1015]: info: Install script completed Jul 02 08:44:03 volumio volumio[1015]: info: Done installing plugin. Jul 02 08:44:03 volumio volumio[1015]: info: Enabling plugin calmradio Jul 02 08:44:03 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 08:44:03 volumio volumio[1015]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Bandcamp Discover","name":"bandcamp","category":"music_service","version":"1.2.0","icon":"fa-bandcamp","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.3","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"1.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true}] Jul 02 08:44:03 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 02 08:44:03 volumio volumio[1015]: info: Folder /tmp/plugins removed Jul 02 08:44:03 volumio volumio[1015]: info: Folder /tmp/downloaded_plugin.zip removed Jul 02 08:44:03 volumio volumio[1015]: info: Folder /data/temp removed Jul 02 08:44:06 volumio volumio[1015]: info: Getting Spotify volume Jul 02 08:44:06 volumio volumio[1015]: (node:1015) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:06 volumio volumio[1015]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Jul 02 08:44:06 volumio volumio[1015]: (node:1015) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 17) Jul 02 08:44:06 volumio volumio[1015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 02 08:44:06 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:06 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:06 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Jul 02 08:44:06 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Jul 02 08:44:06 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jul 02 08:44:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:44:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 182. Jul 02 08:44:06 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:44:06 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:44:06 volumio go-librespot[16964]: go-librespot daemon starting... Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=debug msg="app state loaded" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+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]" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+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]" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=info msg="zeroconf server listening on port 34131" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=debug msg="obtained new client token: AAAZxm9yX6CWqselvlp1QJ3MWyaXK4oc5HStS9M5j5UnGFaOJFZjCuxX+Y+vyOL3Vc+xuRHFdcGYp9VHpkqoA6o8Ei/CXiaDe48gG99XelM4ELbA4SQdGUXRVDjS7V/I9EcmcV67EyK+lWN4vgBOAIckrWaNm2RpvxoHxhKzNU7KKLxgAvy7o1mt1JVAJffOS5ygFQuP4mSeM7LNBrK77TD4xUYky0e48eB7LKRaMrQVc5y9TgpWs1I=" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=debug msg="completed keyexchange" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=debug msg="completed challenge" Jul 02 08:44:06 volumio go-librespot[16964]: time="2025-07-02T08:44:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:44:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:44:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:09 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:09 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:44:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 183. Jul 02 08:44:10 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:44:10 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:44:10 volumio go-librespot[16973]: go-librespot daemon starting... Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=debug msg="app state loaded" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+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]" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+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]" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+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]" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=info msg="zeroconf server listening on port 37705" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=debug msg="obtained new client token: AACroNZhNrD4HINcFMYvkht3UYDduntqM73c+W1zJS9l1K7Nw5eJAhMd2scfmeuhQOXIdL0slZV+BRpdaq3pyloGq8HpORdKAkmtM1rUdsnkGGP/G6i3BjPNEWx3DvgzgkM6YxiULATJluykTDoOrYymI1WMM7W9iSt2mnwOopt6mqMD5rS9UaUKCaIg6w0Pegamm0FFAPFp2JQj1jx+kzxiesHWjfG/3qfD3PskU0IUwHFo8EAAbxI=" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=debug msg="completed keyexchange" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=debug msg="completed challenge" Jul 02 08:44:10 volumio go-librespot[16973]: time="2025-07-02T08:44:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:44:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:44:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:12 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:12 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:44:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 184. Jul 02 08:44:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:44:13 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:44:13 volumio go-librespot[16983]: go-librespot daemon starting... Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=debug msg="app state loaded" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+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]" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+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]" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=info msg="zeroconf server listening on port 39691" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=debug msg="obtained new client token: AAAwzC/Ms0w8CIkOGr9ENKjyIdAP6KjRuaJ3E5bQezYUZf5hxd54GciuQQwq1lJ0l0Dk4lfxOHjQqyHg5FCpf1KeMAEytvut9BviEQajmIucVCTOR5Y+dAOa1ZKKbvbf+YED5+1iszTTiBxnmDTda7wWjvCohZXb/xcN8UdyBTjOQahnkzeANX88DWOVwANZh+OIHrLtcyQaZ+mCLdZMn/HFTZLw+2KVnOZrRjJAwMA0QP+YtQHRC+I=" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=debug msg="completed keyexchange" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=debug msg="completed challenge" Jul 02 08:44:13 volumio go-librespot[16983]: time="2025-07-02T08:44:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:44:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:44:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:14 volumio volumio[1015]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 02 08:44:15 volumio volumio[1015]: info: Initializing connection to go-librespot Websocket Jul 02 08:44:15 volumio volumio[1015]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 08:44:17 volumio volumio[1015]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 02 08:44:17 volumio volumio[1015]: info: FusionDsp - Stopping FusionDsp service Jul 02 08:44:17 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 08:44:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 08:44:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 185. Jul 02 08:44:17 volumio volumio[1015]: TypeError: Cannot read property 'stop' of null Jul 02 08:44:17 volumio volumio[1015]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jul 02 08:44:17 volumio volumio[1015]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 02 08:44:17 volumio volumio[1015]: at /volumio/app/pluginmanager.js:956:12 Jul 02 08:44:17 volumio volumio[1015]: at ChildProcess.exithandler (child_process.js:374:7) Jul 02 08:44:17 volumio volumio[1015]: at ChildProcess.emit (events.js:400:28) Jul 02 08:44:17 volumio volumio[1015]: at maybeClose (internal/child_process.js:1088:16) Jul 02 08:44:17 volumio volumio[1015]: at Socket. (internal/child_process.js:446:11) Jul 02 08:44:17 volumio volumio[1015]: at Socket.emit (events.js:400:28) Jul 02 08:44:17 volumio volumio[1015]: at Pipe. (net.js:686:12) Jul 02 08:44:17 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 08:44:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 08:44:17 volumio systemd[1]: Started go-librespot Daemon. Jul 02 08:44:17 volumio go-librespot[17007]: go-librespot daemon starting... Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=debug msg="app state loaded" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+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]" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+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]" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+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]" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=info msg="zeroconf server listening on port 41269" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=debug msg="obtained new client token: AACBuca1415aMP9iqw1Umn4FQ6cC39t/CD4xOxbm9cjht2hzfapPIrOFERafHcgOtmiPZiiLuh4UQUKR03TrRtXrLlQheVfujWbXPoE48I6D75TNkkhckITOEdJX/DMQQ3JoNGObKHoCyMoYKLwuKCY6PZZnWvOZwO1VcFCv1HyalIriO7ZdJr5Xu7MhltTRS+fKXT8JVnBAD3BsRfIaPPx88InC9vLXLaUexqJYxfMgGkflLS3n87w=" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=debug msg="completed keyexchange" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=debug msg="completed challenge" Jul 02 08:44:17 volumio go-librespot[17007]: time="2025-07-02T08:44:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jul 02 08:44:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 08:44:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 08:44:17 volumio sudo[17023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 08:43 Jul 02 08:44:17 volumio sudo[17023]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"