-- Logs begin at Sat 2025-02-22 13:35:24 CET, end at Sat 2025-02-22 15:42:23 CET. -- Feb 22 15:41:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80549. Feb 22 15:41:00 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:00 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:00 minidsp-shd go-librespot[30156]: Librespot-go daemon starting... Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=info msg="generated new device id: ca31de05f64ee509c5557c8b1acde6232038a7cc" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:00 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="new websocket client" Feb 22 15:41:00 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="zeroconf server listening on port 41487" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="obtained new client token: AAD70B5drrTF7KaXxfLfLktbDQkF6Egyf8ZyhfEva5VMCqKnSAeiOq0sVjff83uB5jdjF+WNbuB7y53zAfZvoxzenpAQpLcrtcZVHVr+9cKqKCzHp8H/8sRCEPBu4ubQYe1J/ti6WM77tydKBVBP3WqjPYuTXV/VpDz8v0ccstEbmg1OGZiMxDwlU06S61LRnfc8PJ3deL5x3Fos5mJmzFyUuGYtaME38Kq5+f+hGTvRP02XijMOjaOd0x1BSw==" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:00 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:00+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:01 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:01+01:00" level=debug msg="completed challenge" Feb 22 15:41:01 minidsp-shd go-librespot[30156]: time="2025-02-22T15:41:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:01 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:41:03 minidsp-shd volumio[621]: info: Getting Spotify volume Feb 22 15:41:03 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:03 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 22 15:41:03 minidsp-shd volumio[621]: (node:621) 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: 26985) Feb 22 15:41:03 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 22 15:41:03 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:41:03 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 15:41:04 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:04 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80550. Feb 22 15:41:04 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:04 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:04 minidsp-shd go-librespot[30172]: Librespot-go daemon starting... Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=info msg="generated new device id: 34099f1be6f1e2c8cc73f76f9fc4e44673d95fb6" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="zeroconf server listening on port 37595" Feb 22 15:41:04 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:04+01:00" level=debug msg="obtained new client token: AAArmhrFCwztzxnMKZQIhrA90IBh0+PPAJDTsqS8c+AZQYyq+VZ1G1DZ7BMTP2MCsL0V3wAeTtz5v/Mbo3dEhnuCe3zm2J3e5h+Z7WnfQRhZrYgKqsGc2zj3/oQDQnkzM+VEoM8Ro3+hCf3zz58CGE06twWgcwJ/hLls/Exi5C0wxkvRCfGCz6oLiPFkIhdJnlg7xRIUaZQqxqcO0+SD8JGojFCLtXuDDmin0X9hAtvmvQijfK784cszpy7naQ==" Feb 22 15:41:05 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:05+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:05 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:05+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:05 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:05+01:00" level=debug msg="completed challenge" Feb 22 15:41:05 minidsp-shd go-librespot[30172]: time="2025-02-22T15:41:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:07 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:07 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80551. Feb 22 15:41:08 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:08 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:08 minidsp-shd go-librespot[30188]: Librespot-go daemon starting... Feb 22 15:41:08 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:08+01:00" level=info msg="generated new device id: 1f9fb3fc5e4227f26248f07ce96afde157d056e3" Feb 22 15:41:08 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:08+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="zeroconf server listening on port 39699" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="obtained new client token: AACanNBI2JVtX/srkn6Dpaf1O97r9XZ6LVFQaJcqJBgxpz+OpdzhJ2GpZ+p9RVTE+4reDUhhAruYy3xfmCj45LP/d1nHYCsKRgdU4CpzlziAq1mrHercdDy4prR0agLjOMyklsRcnHmmemJ91hY/qWR1qXdU/IEvWSXAIUwpvYN02w9BtbBp8RaQDcImhaMWq8KKcf1r+UgR7q/vs9qEmGw8ys356QKX82Sw8MuRl9z1krMK1ODr7xyQbuqcrw==" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:09 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:09+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:10 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:10+01:00" level=debug msg="completed challenge" Feb 22 15:41:10 minidsp-shd go-librespot[30188]: time="2025-02-22T15:41:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:10 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:10 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80552. Feb 22 15:41:13 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:13 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:13 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:13 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:13 minidsp-shd go-librespot[30207]: Librespot-go daemon starting... Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=info msg="generated new device id: 8e00eaa445078750431d937f65b68b345d5df418" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="zeroconf server listening on port 46505" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=debug msg="obtained new client token: AAB3zekW4g7hGaeyUfd41HDWxePjdM2crqC10t932it5jr6k0IQ63+TL3KRLWGpy7rA3dUn3q/BVFrVbLDl+qcWBEFc6hLP6ptXDCG5ucIjipG+JSI9lg1exdE1Gyzpcy1+r8OJY6Gz8f5XJfnweG5gzlJnDsyQBT63L9O9CpjRVxVhtigfcwVRMvaKb8F0V5e0yWsuhob24ZlAjIBbvHUcU533kWAHEIrdsx4PwOeH/r4D93uUy/OntgIwscg==" Feb 22 15:41:13 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:13+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:14 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:14+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:14 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:14+01:00" level=debug msg="completed challenge" Feb 22 15:41:14 minidsp-shd go-librespot[30207]: time="2025-02-22T15:41:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:16 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:16 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80553. Feb 22 15:41:17 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:17 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:17 minidsp-shd go-librespot[30261]: Librespot-go daemon starting... Feb 22 15:41:17 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:17+01:00" level=info msg="generated new device id: e9ef406ff1d687645e5d33b4b3de52374525d3d0" Feb 22 15:41:17 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:17+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:17 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:17 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:17 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=debug msg="zeroconf server listening on port 38885" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=debug msg="obtained new client token: AADZUm6yBOR+ytwhWlw0gKqrKSuPlZZ0UpfuWom3j5MJMY4QlX4Gmg+Lf1sGLl2Wic2HhJarQGaZ4KcgwxsZD6s5gjBbtcW04WZ2fGsoag55qRMZjCIA6h+kgHrTV6RqpLo98eFCa49FU420636WGTKzFD/hHaaCkit+c4NainvCnnAs94HJdXc2IRRPVMy/9pWaB9N28k6HSqYYAj6oxzT6IMyDnpjFoejxbbygS3bD5BIpjcNUW0AFiF8=" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=debug msg="completed challenge" Feb 22 15:41:18 minidsp-shd go-librespot[30261]: time="2025-02-22T15:41:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:19 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:19 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80554. Feb 22 15:41:21 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:21 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:21 minidsp-shd go-librespot[30279]: Librespot-go daemon starting... Feb 22 15:41:21 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:21+01:00" level=info msg="generated new device id: e8d4dbe524e61e7ba790eafd614c4bc93996eba4" Feb 22 15:41:21 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:21+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="zeroconf server listening on port 41497" Feb 22 15:41:22 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="new websocket client" Feb 22 15:41:22 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="obtained new client token: AAB2GkSEhBV0hRASSTp1w9WSWHnOL6TboTnpLo9byB33JUTvxubRbBM9GAJeo5+UVhsBVdVBNc+4PykRiFjbCTysP/7gr1pexiQ0Oq7Ie1BaXrmjwnS05bCj2dNUPo6lH7zSk9PsZIb7XqhaDV79amv0Db6cPqPnDGe3u7GbynOVWybFyge07lXfEjxoBJd8krqAiqQ1mBkj7Z2DkCa4lGjHxUmIVCCb2B+B93PfvG5qc0BmQj5X3cwlbUpNGw==" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:22 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:22+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:23 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:23+01:00" level=debug msg="completed challenge" Feb 22 15:41:23 minidsp-shd go-librespot[30279]: time="2025-02-22T15:41:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:23 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:41:25 minidsp-shd volumio[621]: info: Getting Spotify volume Feb 22 15:41:25 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:25 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 22 15:41:25 minidsp-shd volumio[621]: (node:621) 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: 26986) Feb 22 15:41:25 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 22 15:41:25 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:41:25 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 15:41:26 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:26 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80555. Feb 22 15:41:26 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:26 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:26 minidsp-shd go-librespot[30295]: Librespot-go daemon starting... Feb 22 15:41:26 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:26+01:00" level=info msg="generated new device id: 049a87da4f2f0271397665b97652c7d8914ab8b6" Feb 22 15:41:26 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:26+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="zeroconf server listening on port 36239" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="obtained new client token: AADOlWKzHOwsexOn1BcDaI9Tum/2so4NRrq6ZIqC3YZRae/V/h97jTac5OLo0UopKyST7h/YTXYuZHTcwPpvqVFAzTMdwVQcfMpFoL6lQCw6A1usaOSXPGH4BFpvxtWOJolXnFr0jHby6nkuPnWUZnm+Gju7f1w1Tod6PuSdn94BkyG5NYexM6YwvzP2hLnwUAkLXHlrpObehCj7o80l2vKz2iAfjxOYcuiiuuK7t3xT2hMZvW4d2ulD57+0cg==" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:27 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:27+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:28 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:28+01:00" level=debug msg="completed challenge" Feb 22 15:41:28 minidsp-shd go-librespot[30295]: time="2025-02-22T15:41:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:29 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:29 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80556. Feb 22 15:41:31 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:31 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:31 minidsp-shd go-librespot[30313]: Librespot-go daemon starting... Feb 22 15:41:31 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:31+01:00" level=info msg="generated new device id: 7161ab129ca3e147338064c794c0c361d841f896" Feb 22 15:41:31 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:31+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="zeroconf server listening on port 45977" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="obtained new client token: AABNr40J4Y7C0UKbIa1PLyuu0nFThffNurbdrna4VUudoDZm5Q3SffLHkdJ1llZR8rb5p7shRywyiUkc8snBrMGeI4wItOOn0uWU0hsihnfM+hpBbF7yj/FIBHFZjzsIIqPmI4BZdZ5DnH421KjJ/cT8X9HK9mAn8PB1RpYiyQi/rdK4YxZsG65Hj3umPxALmZDZ71WIYH8bwoD5K239LcFD4R4li1p3T3Dimri0er+JiznCI0RSKU6XrZ4=" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:32 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="new websocket client" Feb 22 15:41:32 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=debug msg="completed challenge" Feb 22 15:41:32 minidsp-shd go-librespot[30313]: time="2025-02-22T15:41:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:32 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:32 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:32 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:41:35 minidsp-shd volumio[621]: info: Getting Spotify volume Feb 22 15:41:35 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:35 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 22 15:41:35 minidsp-shd volumio[621]: (node:621) 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: 26987) Feb 22 15:41:35 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 22 15:41:35 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:41:35 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 15:41:35 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:35 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:36 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:36 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80557. Feb 22 15:41:36 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:36 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:36 minidsp-shd go-librespot[30332]: Librespot-go daemon starting... Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=info msg="generated new device id: 243c18b5dda3369d5b16e783f66c8e72c56afe4f" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="zeroconf server listening on port 35195" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="obtained new client token: AABJtXyhJnjvCbY1nbVO2N//bdMIaIwvq1dbyIVCH2E7w4AD8mU/2wbaSIpX1Ee+DQXA99vJLxdYCQm8sam6/yQYH0Duc9bX0xVdC1f/U/JH5cQ1ARDiK+9pZslkmW6PyGYTHNInQJvGbe7Y8YGRatnhwsspQb+TlXlzUUfCGeAFGzq17BI0SSBFd4TOzTtEj33KDvS6Meie/ec1afvKwZe/bSvRIo3BrD5MghbOyDTej5vviBEf+f87gYAlmg==" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:36 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:36+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:37 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:37+01:00" level=debug msg="completed challenge" Feb 22 15:41:37 minidsp-shd go-librespot[30332]: time="2025-02-22T15:41:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:38 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:38 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80558. Feb 22 15:41:40 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:40 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:40 minidsp-shd go-librespot[30349]: Librespot-go daemon starting... Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=info msg="generated new device id: 9593faf8f991643615612264550c2611fb2dd776" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="zeroconf server listening on port 33719" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=debug msg="obtained new client token: AAALMSmlyzzDocePEZOo+7LNrOEmkVgqNNSMdkwjoP0YbXE/IY2vOWviaG1ci+T5PvL781qlyol6xDfl+Z3e7X6KcAhgcs5UeIXOwRaxFVyv7jtllrvMSjLFeNzTF0rWwdJilguuHdZH8qdCxzpcoH8NI9uc7evAM9lnV6FM0RmXdT59L0WV8EmFYd2MtZ3V/cDb87DaGgkGxATWwRun8cfjx9v9tlXZOtpWx6BPV5UxfN6oRz/V8nS8cjpAYA==" Feb 22 15:41:40 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:40+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:41 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:41+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:41 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:41+01:00" level=debug msg="completed challenge" Feb 22 15:41:41 minidsp-shd go-librespot[30349]: time="2025-02-22T15:41:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:41 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:41 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80559. Feb 22 15:41:44 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:44 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:44 minidsp-shd go-librespot[30365]: Librespot-go daemon starting... Feb 22 15:41:44 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:44+01:00" level=info msg="generated new device id: 797b8bcb5c03c15112c5831f6c1ed291bd08d2e7" Feb 22 15:41:44 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:44+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:44 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:44 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:44+01:00" level=debug msg="new websocket client" Feb 22 15:41:44 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="zeroconf server listening on port 39563" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="obtained new client token: AACgylf9NgvukZ9ltayk3kU2GvZQOK7vy4kKlxPGylXypju+7otX7klPXgLfHO9y7yV2f+zWhaxTcnheH38VWkilc/ieRd44SCAPpDQDl2CIL5dgFqcP8uHisXjyn+jrFFJspMdzn8kRSWhArCsn5faEwBXBHWB/vYmEdB3hoWZPMOlkva1IaKBxCUFdss1sSIDa2St5z179utXN6EJxOrgkpb19Mj3aV379pK6L1I6amxjP5EQbSqJ3WL8=" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=debug msg="completed challenge" Feb 22 15:41:45 minidsp-shd go-librespot[30365]: time="2025-02-22T15:41:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:45 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:45 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:45 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:41:47 minidsp-shd volumio[621]: info: Getting Spotify volume Feb 22 15:41:47 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:47 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 22 15:41:47 minidsp-shd volumio[621]: (node:621) 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: 26988) Feb 22 15:41:47 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 22 15:41:47 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:41:48 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 15:41:48 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:48 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:49 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:49 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80560. Feb 22 15:41:49 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:49 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:49 minidsp-shd go-librespot[30383]: Librespot-go daemon starting... Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=info msg="generated new device id: 515392d705f5a0c70375ffcfe608545547f50fc6" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="zeroconf server listening on port 43147" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="obtained new client token: AACndNKt92zySmVW0mi4m0fwdeJrRCZaKgLtBiRop6hUjVQ9QGJCQxY98hzIwcN1NdbvPkfx2Wc3WK+pml9sL5nzuuSK49EgWg6Ndgc6ZgxJdCpk9fEF57FSytpDLcildUZLUUadAqXGD7CkjIAvFpSqCrjZNtAU/nY9J+oZAj2J+OXXmd5MUXH20mnd37d2yQ3e/NSPjNH5abMwhQ2KHYnBj34WqLNQ8ffJxAV41VdX/WtEjoEIyxXiTzr0eQ==" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:49 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:49+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:50 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:50+01:00" level=debug msg="completed challenge" Feb 22 15:41:50 minidsp-shd go-librespot[30383]: time="2025-02-22T15:41:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:50 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:50 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:51 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:51 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:53 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:53 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80561. Feb 22 15:41:53 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:53 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:53 minidsp-shd go-librespot[30401]: Librespot-go daemon starting... Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=info msg="generated new device id: b40006b140df7c041f5c9c69d26f14d28656d385" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="zeroconf server listening on port 42487" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="obtained new client token: AAB+oxKqTEJOB9FIk7Nu71YaF54wH/OQX61d9x+3X9F7luVxx0rWFfd9AI5GH9QHVRrXorEBkZcoVT33TSoZYkZJklimfQjitFGOw3LWu2NWNZ8LWVSfG3ea7yd84qeSaPMLGkRacPqq4N8YZm6gaAWb6Iw8GmUnOTNowFI89CrtTxDWi+ZKSF3i6cCkZazz1FKzWH6BdZ7vmduM463eokr4w6MZsOCXDvTj7YzpT09uyhTUXmPhNYjSbcp0Bg==" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:53 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:53+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:54 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:54+01:00" level=debug msg="completed challenge" Feb 22 15:41:54 minidsp-shd go-librespot[30401]: time="2025-02-22T15:41:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:54 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:54 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:41:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:41:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80562. Feb 22 15:41:57 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:41:57 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:41:57 minidsp-shd go-librespot[30418]: Librespot-go daemon starting... Feb 22 15:41:57 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:57+01:00" level=info msg="generated new device id: 497301b62cb5a22f49d51038bb4058fff5095d44" Feb 22 15:41:57 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:57+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:41:57 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:41:57 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:57+01:00" level=debug msg="new websocket client" Feb 22 15:41:57 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="zeroconf server listening on port 46507" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="obtained new client token: AACuyuG+WL8o+cZONPbp52a+3RgsUtruru9Aps039Gdr1DzILa6/AGnlfQT43mgZNo54Dcdo6GBOPWE7oaHlFGit0h98OXYYgmkyZFXPm2KRO2e7+pop2yv9pKh92tPg1DlWYRVOjtoHwpzJQYTDKWrQFCVw9PUko6r03u2Ni3mI3vTbLKXqQK5L055/PE0uBPxSRJxxhpRY/JZ9sM4MmDp+8Wm7wmKiUMUqibXEZ1fqwGOjv80RhsGvp6M=" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="completed keyexchange" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=debug msg="completed challenge" Feb 22 15:41:58 minidsp-shd go-librespot[30418]: time="2025-02-22T15:41:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:41:58 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:41:58 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:41:58 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:42:00 minidsp-shd volumio[621]: info: Getting Spotify volume Feb 22 15:42:00 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:00 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 22 15:42:00 minidsp-shd volumio[621]: (node:621) 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: 26989) Feb 22 15:42:01 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:01 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:01 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:01 minidsp-shd volumio[621]: verbose: New Socket.io Connection to 192.168.1.27:3000 from 192.168.1.226 UA: Dart/3.5 (dart:io) Total Clients: 7 Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:42:01 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:42:01 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:01 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80563. Feb 22 15:42:01 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:01 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:01 minidsp-shd go-librespot[30436]: Librespot-go daemon starting... Feb 22 15:42:01 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:01+01:00" level=info msg="generated new device id: 8216a0b39f5b18bbc6679577fed5f2ba4c3dbb8a" Feb 22 15:42:01 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:01+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="zeroconf server listening on port 35691" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="obtained new client token: AABQ9ACPDvI89vuizDdmxc56D1MIQksCKdGae/yUTnFxHv3gYWl8e1anKc693r1qM1rd50Mfw8J3u4m4NZfv5vfprV7KMgD84dX5sqRxX+1CqvEzZrMTTPtmVaXEj5m+J77ocvA4vX2t0Rxk/0XkJc5c6BTO2BSwnbdJS6L1B6T/q7264u+ELGCMNb7o8UbqQzC3i9ZlJppq4oQ2d+6SCEBiyLUon1HVy/zoTXiJ44oZyUOGCvAPYTdyBpWQ3g==" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:42:02 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:02+01:00" level=debug msg="completed keyexchange" Feb 22 15:42:03 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:03+01:00" level=debug msg="completed challenge" Feb 22 15:42:03 minidsp-shd go-librespot[30436]: time="2025-02-22T15:42:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:42:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:42:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:42:04 minidsp-shd sudo[30449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:42:04 minidsp-shd sudo[30449]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 15:42:04 minidsp-shd sudo[30449]: pam_unix(sudo:session): session closed for user root Feb 22 15:42:04 minidsp-shd sudo[30452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:42:04 minidsp-shd sudo[30452]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 15:42:04 minidsp-shd sudo[30452]: pam_unix(sudo:session): session closed for user root Feb 22 15:42:04 minidsp-shd volumio[621]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.226 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 8 Feb 22 15:42:04 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:04 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:04 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 15:42:05 minidsp-shd volumio[621]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Feb 22 15:42:05 minidsp-shd volumio[621]: info: Received Get System Info Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:05 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:05 minidsp-shd volumio[621]: info: Listing playlists Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:42:05 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 15:42:05 minidsp-shd sudo[30459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:42:05 minidsp-shd sudo[30459]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 15:42:05 minidsp-shd sudo[30459]: pam_unix(sudo:session): session closed for user root Feb 22 15:42:05 minidsp-shd sudo[30462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:42:05 minidsp-shd sudo[30462]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 15:42:05 minidsp-shd sudo[30462]: pam_unix(sudo:session): session closed for user root Feb 22 15:42:05 minidsp-shd volumio[621]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.226 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 9 Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetQueue Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreStateMachine::getQueue Feb 22 15:42:06 minidsp-shd volumio[621]: info: CorePlayQueue::getQueue Feb 22 15:42:06 minidsp-shd volumio[621]: info: Listing playlists Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 15:42:06 minidsp-shd volumio[621]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Feb 22 15:42:06 minidsp-shd volumio[621]: info: Received Get System Info Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:06 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:06 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:06 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80564. Feb 22 15:42:06 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:06 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:42:06 minidsp-shd go-librespot[30468]: Librespot-go daemon starting... Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=info msg="generated new device id: dd8c4b26e2d7647d60d03a7fa158eb29b211abc2" Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:06 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="zeroconf server listening on port 34131" Feb 22 15:42:06 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:06+01:00" level=debug msg="obtained new client token: AAB+rSCfanAxRNBsv42Gl+/96eimyqG3f598P1BsXq33yzscu4//clJZF3r1wqv+HubxN1jV91+rmNilsNyY2Ylew/qfkI58EAhG9dXWdqKRVTFz93bsTVnthElvihiJDlLMsPdOiVvT9MxklJ2JYuvWQ4Sro+GmnZVqr6j1ovpLuLDaig6xcoX4VISLBsXgjlrhodiFvkfvlGHqqw8LJwrsGZR9LvCbxT3ls3NYvfIjiy0U1c86oxkc217I6A==" Feb 22 15:42:07 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:07+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:42:07 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:07+01:00" level=debug msg="completed keyexchange" Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:42:07 minidsp-shd volumio[621]: info: Received Get System Info Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:07 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:07 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 15:42:07 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:07+01:00" level=debug msg="completed challenge" Feb 22 15:42:07 minidsp-shd go-librespot[30468]: time="2025-02-22T15:42:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:42:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:42:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:42:07 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:07 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:09 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:42:09 minidsp-shd volumio[621]: info: Received Get System Info Feb 22 15:42:09 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:09 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:09 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:09 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:09 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 22 15:42:10 minidsp-shd volumio[621]: info: handleBrowseUri took 227 milliseconds Feb 22 15:42:10 minidsp-shd volumio[621]: info: Preload queue cleared Feb 22 15:42:10 minidsp-shd volumio[621]: info: Preload queue cleared Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:42:10 minidsp-shd volumio[621]: info: Received Get System Info Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:10 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:10 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:10 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:10 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80565. Feb 22 15:42:10 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:10 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:10 minidsp-shd go-librespot[30487]: Librespot-go daemon starting... Feb 22 15:42:10 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:10+01:00" level=info msg="generated new device id: 1466062344aec060583f97a78df35c5811b858d4" Feb 22 15:42:10 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:10+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="zeroconf server listening on port 44805" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="obtained new client token: AACEHowEbaOtBVykMz9IilUafASgYAP1cBGirl8Y3Az+fdraUJQ6B2gMEafUn+OlfcO63JghTUmJfPaH78FMcvrkXzhpnblCmplQHvtEyt57pc40BluMhZTr6QqHJtAZ9exb27Bdq6YddkUVxvtDnl2rq/byh6rYlJGuRh6QSOBMmQW1cAjGcPCg94NWfEfzccs25Qwrt+G6MFczCKWPhVyWcFFaXxQCUhhIN5H/E5YmxMs8ax8XT8EWg8pRMg==" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Feb 22 15:42:11 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:11+01:00" level=debug msg="completed keyexchange" Feb 22 15:42:12 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:12+01:00" level=debug msg="completed challenge" Feb 22 15:42:12 minidsp-shd go-librespot[30487]: time="2025-02-22T15:42:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:42:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:42:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:42:13 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:13 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80566. Feb 22 15:42:15 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:15 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:15 minidsp-shd go-librespot[30504]: Librespot-go daemon starting... Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=info msg="generated new device id: d297a0a2f65fffbdb13900f4f6eb9f657f3fc9b3" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="zeroconf server listening on port 42707" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=debug msg="obtained new client token: AACaOpgQhSjW0joKgymcxtlEJIaLxuI1xEjIG64sBO5sTRHVhcNe+PnrU0KitGqfTm9dZ1pCn14PKy7n6J9nAB2PVepsRZAAC3Y2QZecYMnLhY11VGs8vopyT9PP15noI25GvX/wJk8e+3kYBvcfXe/rAdM9cEqCg4Vos/tcdRDj9kcUz+J8aZqHRAFdIL6CePRTZLvpZNzcmdGLGpTA6GHwIh6eW5sZuIkIJ0ompLgnNz75HsRSGDyuLwcX1w==" Feb 22 15:42:15 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:15+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:42:16 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:16+01:00" level=debug msg="completed keyexchange" Feb 22 15:42:16 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:16+01:00" level=debug msg="completed challenge" Feb 22 15:42:16 minidsp-shd go-librespot[30504]: time="2025-02-22T15:42:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:42:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:42:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:42:16 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:16 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 15:42:18 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:42:18 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:42:18 minidsp-shd volumio[621]: info: Discovery: Getting this device information Feb 22 15:42:18 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Feb 22 15:42:18 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:42:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80567. Feb 22 15:42:19 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:19 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:19 minidsp-shd go-librespot[30558]: Librespot-go daemon starting... Feb 22 15:42:19 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:19+01:00" level=info msg="generated new device id: 2c13077ba5b2d7a44e11fa371cd576549e22f834" Feb 22 15:42:19 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:19+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:19 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Feb 22 15:42:19 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:19+01:00" level=debug msg="new websocket client" Feb 22 15:42:19 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="zeroconf server listening on port 44775" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="obtained new client token: AAAKyK3WqVNcc0GnZu09KoaZKVD7sAOcEK/My7Pj6zMOtx/d6Bh2ckx5cef8m4CsHfKGnOc2vRZo+Ny0IeG3/ogId8YTY5m+mTznhLpVcuJfUZ3Aa9V92G7A4Ukk4N1o7kIDbLaJY+G+KWW26ElYjhtuRoivIHYRU3bq/jRT5GehU9a6pHSf0yKZS8VPMnUDR8RFtMzOWqtryAaDdJcXSKFJGdsn7afDaAl6okyMi4RR9e+hz00E633RdgQ=" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="completed keyexchange" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=debug msg="completed challenge" Feb 22 15:42:20 minidsp-shd go-librespot[30558]: time="2025-02-22T15:42:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 22 15:42:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 15:42:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 15:42:20 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Feb 22 15:42:22 minidsp-shd volumio[621]: Searching all installed plugins Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Feb 22 15:42:22 minidsp-shd volumio[621]: info: [1740235342876] ControllerCalmRadio::searchCategories Feb 22 15:42:22 minidsp-shd volumio[621]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:42:22 minidsp-shd volumio[621]: TypeError: Cannot read property 'cats' of undefined Feb 22 15:42:22 minidsp-shd volumio[621]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Feb 22 15:42:22 minidsp-shd volumio[621]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Feb 22 15:42:22 minidsp-shd volumio[621]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Feb 22 15:42:22 minidsp-shd volumio[621]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Feb 22 15:42:22 minidsp-shd volumio[621]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Feb 22 15:42:22 minidsp-shd volumio[621]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Feb 22 15:42:22 minidsp-shd volumio[621]: at Socket.emit (events.js:400:28) Feb 22 15:42:22 minidsp-shd volumio[621]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 22 15:42:22 minidsp-shd volumio[621]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 22 15:42:22 minidsp-shd volumio[621]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:42:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 15:42:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80568. Feb 22 15:42:23 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Feb 22 15:42:23 minidsp-shd systemd[1]: Started go-librespot Daemon. Feb 22 15:42:23 minidsp-shd go-librespot[30580]: Librespot-go daemon starting... Feb 22 15:42:23 minidsp-shd go-librespot[30580]: time="2025-02-22T15:42:23+01:00" level=info msg="generated new device id: cf7682d8b82c7315c9052baa957c782e0a1d6d45" Feb 22 15:42:23 minidsp-shd go-librespot[30580]: time="2025-02-22T15:42:23+01:00" level=debug msg="stored credentials found for saschabies80" Feb 22 15:42:23 minidsp-shd sudo[30583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-22 15:41 Feb 22 15:42:23 minidsp-shd sudo[30583]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"