-- Logs begin at Wed 2024-06-12 20:22:31 CEST, end at Sat 2024-07-20 11:03:52 CEST. -- Jul 20 11:02:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 977. Jul 20 11:02:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:01 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:01 volumio go-librespot[20921]: Librespot-go daemon starting... Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=info msg="generated new device id: 9950910bbdecf8edf6f56921e05308817621727c" Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=debug msg="obtained new client token: AABSViswpKCZMXHvc50P2VHw0VDzV6PvRUN6G60kHbG8krsy0Qp0njz5bL1UvltyELkNjbt8GFNLsrWMkHB/e8JQNixO7iK+LPek1wNXiIh+ablQUy2sWFmATUB3p3QabW1uJhv50d+JCg1PTnDWQHMlhe1c67jPjs/CTvJoKAy+FdhkbkQ/NFnJMxUI7suGEOtXnuNTTSPlBOxerv6A+daD3JFbcS/0VzOCx4z3Gt4CtXntLoYjvY8uHLqcda+g" Jul 20 11:02:01 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=debug msg="new websocket client" Jul 20 11:02:01 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:01 volumio go-librespot[20921]: time="2024-07-20T11:02:01+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:02 volumio go-librespot[20921]: time="2024-07-20T11:02:02+02:00" level=debug msg="completed challenge" Jul 20 11:02:02 volumio go-librespot[20921]: time="2024-07-20T11:02:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:02 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:02:04 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:02:04 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:04 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:02:04 volumio volumio[752]: (node:752) 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: 1484) Jul 20 11:02:04 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:02:04 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:02:04 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:02:05 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:05 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 978. Jul 20 11:02:05 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:05 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:05 volumio go-librespot[20929]: Librespot-go daemon starting... Jul 20 11:02:05 volumio go-librespot[20929]: time="2024-07-20T11:02:05+02:00" level=info msg="generated new device id: 059195d5fb7b8267d99932f250e804e770eec980" Jul 20 11:02:05 volumio go-librespot[20929]: time="2024-07-20T11:02:05+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:05 volumio go-librespot[20929]: time="2024-07-20T11:02:05+02:00" level=debug msg="obtained new client token: AABCJr2gPjzYp6AeeDsykpu9X70gAvq5Z5W8U/LTiHK8tuxTT28BEnd+qpottfcmMRjDu0O8Db3lxkVIxnKxLD5Y/wEXiT63SbNi/zCS3Z2OFSCPvMycOkC6EHtZk6i8YgN7E9O9mADIo3KIb50v00kQgkeXYhaQOfgfSnm+xxc+U53HodJhowVjoGgNcbrniAVRryyyZ3m8ZTLskNL39jStHL2lbANEH3m53Bgo+RTzb5EeAMw7krKUoc0YTUaU" Jul 20 11:02:05 volumio go-librespot[20929]: time="2024-07-20T11:02:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:05 volumio go-librespot[20929]: time="2024-07-20T11:02:05+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:06 volumio go-librespot[20929]: time="2024-07-20T11:02:06+02:00" level=debug msg="completed challenge" Jul 20 11:02:06 volumio go-librespot[20929]: time="2024-07-20T11:02:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:08 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:08 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 979. Jul 20 11:02:09 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:09 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:09 volumio go-librespot[20936]: Librespot-go daemon starting... Jul 20 11:02:09 volumio go-librespot[20936]: time="2024-07-20T11:02:09+02:00" level=info msg="generated new device id: a027b23f9c7a47dc62e3a71feefcca83fa40f64d" Jul 20 11:02:09 volumio go-librespot[20936]: time="2024-07-20T11:02:09+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:09 volumio go-librespot[20936]: time="2024-07-20T11:02:09+02:00" level=debug msg="obtained new client token: AACvNmHZnUCpzka0Rugg9Nhg4sao2DU8DzaLD4RBPBCTrhsLKCCTqChJuG00SiltDG6PAfglvKq6NUk34GPB8b5hTdxvimt7uCjzzd8DyQygf9l54VJAYEdsiNuAS2G/Lb7cktjRuv4DI0toucfrO3JhDoIrNLEu0lynrFZ2WE3Fz2YUJ1j0wlllEU3OD7qiIsS1SB279yH/CchTd7xYjU8TmricPjTIgOtT+yG38Ae2xBlx3/UF38OcasImeNBZ" Jul 20 11:02:10 volumio go-librespot[20936]: time="2024-07-20T11:02:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:10 volumio go-librespot[20936]: time="2024-07-20T11:02:10+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:10 volumio go-librespot[20936]: time="2024-07-20T11:02:10+02:00" level=debug msg="completed challenge" Jul 20 11:02:10 volumio go-librespot[20936]: time="2024-07-20T11:02:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:11 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:11 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 980. Jul 20 11:02:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:13 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:13 volumio go-librespot[20943]: Librespot-go daemon starting... Jul 20 11:02:13 volumio go-librespot[20943]: time="2024-07-20T11:02:13+02:00" level=info msg="generated new device id: b3edf4a687d244fcee266e8874838eb45a0f9176" Jul 20 11:02:13 volumio go-librespot[20943]: time="2024-07-20T11:02:13+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=debug msg="obtained new client token: AACUDpVulz2EETfT4wEZ17aYuzdHazt7pbD5MAPdWFB5sOXFGTzyhs5ZeRJmUYEv2NaV0eZ2jexK73j3to+FrevvYStFZxvo23Ny1mY1AxGrXAfPbYrjRsWk9zgbvQI6BwCLt2HuKxhplH+Yd7sHwxd0Vpc5oL91WOKwGW2xGmja8dm5kMgiJoaYQ2ryWdHfctM3x9Qbwt/fnsfLM89rrNvXU5jL8CWt7sXnk7XU5nKXtWodhwp3/mvkUvNaRA==" Jul 20 11:02:14 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=debug msg="new websocket client" Jul 20 11:02:14 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=debug msg="completed challenge" Jul 20 11:02:14 volumio go-librespot[20943]: time="2024-07-20T11:02:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:14 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:02:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:17 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:02:17 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:17 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:02:17 volumio volumio[752]: (node:752) 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: 1485) Jul 20 11:02:17 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:02:17 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:02:17 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:02:17 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:17 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 981. Jul 20 11:02:18 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:18 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:18 volumio go-librespot[20951]: Librespot-go daemon starting... Jul 20 11:02:18 volumio go-librespot[20951]: time="2024-07-20T11:02:18+02:00" level=info msg="generated new device id: 21eb02289b458e787bedc8919b32dfffa061b534" Jul 20 11:02:18 volumio go-librespot[20951]: time="2024-07-20T11:02:18+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:18 volumio go-librespot[20951]: time="2024-07-20T11:02:18+02:00" level=debug msg="obtained new client token: AAC8Ic35aodWVXoaN7J17nSAKbVyh0ih9zPxLZKVrB0cQr1CDeHE+lzQRP8rEXBGlPh7zPrRNSCaoT7SyBcInHXvJbLTf15+SVUsotxr3qwYPMbsGHYGWrQO94/B6crAJot5JIwK9QTiFK8AXGxF57Su2LHhOAcob+2hx21wP/xMBHd18buurZpkSHv7nyuK8rWsiMYYT0V7cAuS3UbrMRmdot1s92b1v5gPcjQNTEiQcm1aEaMw84M2VNO2feBi" Jul 20 11:02:18 volumio go-librespot[20951]: time="2024-07-20T11:02:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:18 volumio go-librespot[20951]: time="2024-07-20T11:02:18+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:19 volumio go-librespot[20951]: time="2024-07-20T11:02:19+02:00" level=debug msg="completed challenge" Jul 20 11:02:19 volumio go-librespot[20951]: time="2024-07-20T11:02:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:20 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:21 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 982. Jul 20 11:02:22 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:22 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:22 volumio go-librespot[20958]: Librespot-go daemon starting... Jul 20 11:02:22 volumio go-librespot[20958]: time="2024-07-20T11:02:22+02:00" level=info msg="generated new device id: fb8d66c7f1385e4337325f824acb0f0eb4653c11" Jul 20 11:02:22 volumio go-librespot[20958]: time="2024-07-20T11:02:22+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:22 volumio go-librespot[20958]: time="2024-07-20T11:02:22+02:00" level=debug msg="obtained new client token: AADr3P5L05EYa5ScRya0jYrcc2wFFXtBNCkP6ikKXAVMztnUvCAjhqypdL5v23QhboYAzer2GVm482/uMMxVqyx+ukX9vtYydxDcscISRCEkltUN6S23S+wUJyJYi7RYVk0uqIHMml/H9XsTfA9yqvwEZ8VCilQaIcmidGsgSyGTj/qj3AruZP8B6Ke1P63bD2LDczEoW1Isz+tpwaYTswSaEf75UF6Q8GeS0aKcb9Eo7hU3WrpekV12KztVQjdA" Jul 20 11:02:22 volumio go-librespot[20958]: time="2024-07-20T11:02:22+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:22 volumio go-librespot[20958]: time="2024-07-20T11:02:22+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:23 volumio go-librespot[20958]: time="2024-07-20T11:02:23+02:00" level=debug msg="completed challenge" Jul 20 11:02:23 volumio go-librespot[20958]: time="2024-07-20T11:02:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:24 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:24 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 983. Jul 20 11:02:26 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:26 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:26 volumio go-librespot[20966]: Librespot-go daemon starting... Jul 20 11:02:26 volumio go-librespot[20966]: time="2024-07-20T11:02:26+02:00" level=info msg="generated new device id: f9963b2111386b3e813beb931c875d9b98e0c9e1" Jul 20 11:02:26 volumio go-librespot[20966]: time="2024-07-20T11:02:26+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:26 volumio go-librespot[20966]: time="2024-07-20T11:02:26+02:00" level=debug msg="obtained new client token: AADhWHD2ANwGsriUXnPQEGjKLve+N/mRkBVhnyGsdVVgzobSV7QP6e23z7t2sW8SROf+oArj+AFjv+cYSwRETIlmP2ssrUE3CK8xiL51ygDmXoIYw7WnzZHbTLxLdPXAtaa/leOm21V8t/a5JVaBTeT7ImdjWqJnKkJs965RyUuAZouaMeDRe6LtO16eLh10Uwd98HHDQWy1f/ZdMnsSTGy6wRE/XgxttgcdwyNwZOCz9ytiMSp+L7r2qBOIplaS" Jul 20 11:02:27 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:27 volumio go-librespot[20966]: time="2024-07-20T11:02:27+02:00" level=debug msg="new websocket client" Jul 20 11:02:27 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:02:27 volumio go-librespot[20966]: time="2024-07-20T11:02:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:02:27 volumio go-librespot[20966]: time="2024-07-20T11:02:27+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:27 volumio go-librespot[20966]: time="2024-07-20T11:02:27+02:00" level=debug msg="completed challenge" Jul 20 11:02:27 volumio go-librespot[20966]: time="2024-07-20T11:02:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:27 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:02:30 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:02:30 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:30 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:02:30 volumio volumio[752]: (node:752) 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: 1486) Jul 20 11:02:30 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:02:30 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:02:30 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:02:30 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:30 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 984. Jul 20 11:02:30 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:30 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:30 volumio go-librespot[20973]: Librespot-go daemon starting... Jul 20 11:02:30 volumio go-librespot[20973]: time="2024-07-20T11:02:30+02:00" level=info msg="generated new device id: f16e1c2784b28f1c8aed3ba10123b5d151bca8f1" Jul 20 11:02:30 volumio go-librespot[20973]: time="2024-07-20T11:02:30+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:31 volumio go-librespot[20973]: time="2024-07-20T11:02:31+02:00" level=debug msg="obtained new client token: AACXG8/bmPHQvXW+kVcE3DLzgR/BSQP8zQGYvus+ZXQFe7jsWmEqAyovpkUPeJZQK6v7liSDGL7gI2i3vy2QY+7sU2zBL6mCQtY6qhWUa/TS4WrP3FyNLAA6lWoiigw5fI/zpvGW4/4HIfjQFvoa6sH9aHP3CXatvr9jsZlHeYkvlsOaVXVMYB+5cQRcZzCQx6oZoRI4lPnFZkaBfsIpilfwcScnGo3p8RRjhwHZjVnzOc25fHFnt0GJocUKow==" Jul 20 11:02:31 volumio go-librespot[20973]: time="2024-07-20T11:02:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:31 volumio go-librespot[20973]: time="2024-07-20T11:02:31+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:31 volumio go-librespot[20973]: time="2024-07-20T11:02:31+02:00" level=debug msg="completed challenge" Jul 20 11:02:32 volumio go-librespot[20973]: time="2024-07-20T11:02:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:33 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:33 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 985. Jul 20 11:02:35 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:35 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:35 volumio go-librespot[20981]: Librespot-go daemon starting... Jul 20 11:02:35 volumio go-librespot[20981]: time="2024-07-20T11:02:35+02:00" level=info msg="generated new device id: 6264b28871f4e55750fbec29f2b5879dd9ec6649" Jul 20 11:02:35 volumio go-librespot[20981]: time="2024-07-20T11:02:35+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:36 volumio go-librespot[20981]: time="2024-07-20T11:02:36+02:00" level=debug msg="obtained new client token: AAAxfVmNOociRoYqGIk/EBeebB/HlroiEndSQxnOUgfkiUFNn5I2TEi6plntMcB2cDFjAGkmE/x941ZcYkeOkRy7n3wZACljrc6QzEtff9sUpOFB1AH4KDRCUX83yrZBOO+jMyIs147U/ichGjdXnh6ZaIX7eV4ONmsHrem/RVxWSQPYpgadu5T9Gv1POQ7SB40zhPyQ+bwU+ybBM3CrwqpRgFbuk47aEOIsMUCC97hs6X7jQzUtwNN0q7bs/fAK" Jul 20 11:02:36 volumio go-librespot[20981]: time="2024-07-20T11:02:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:02:36 volumio go-librespot[20981]: time="2024-07-20T11:02:36+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:36 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:36 volumio go-librespot[20981]: time="2024-07-20T11:02:36+02:00" level=debug msg="new websocket client" Jul 20 11:02:36 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:02:37 volumio go-librespot[20981]: time="2024-07-20T11:02:37+02:00" level=debug msg="completed challenge" Jul 20 11:02:37 volumio go-librespot[20981]: time="2024-07-20T11:02:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:37 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:02:39 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:02:39 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:39 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:02:39 volumio volumio[752]: (node:752) 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: 1487) Jul 20 11:02:39 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:02:39 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:02:39 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:02:40 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:40 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 986. Jul 20 11:02:40 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:40 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:40 volumio go-librespot[20988]: Librespot-go daemon starting... Jul 20 11:02:40 volumio go-librespot[20988]: time="2024-07-20T11:02:40+02:00" level=info msg="generated new device id: 64732aeeab22c837d69328e15294f93e00ad065b" Jul 20 11:02:40 volumio go-librespot[20988]: time="2024-07-20T11:02:40+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:40 volumio go-librespot[20988]: time="2024-07-20T11:02:40+02:00" level=debug msg="obtained new client token: AACeWqbKhd50aypzSbuqWv3yaVa4i3faBxqxHxlcktHnHlLKks8sUMGlViX9++X219K6j2CgeG5Qice2SGTik91YANAttE/BK7pGrj08WkFuwIiM/qXoEcDL5z0RVHLylIpVFJ1zTKkaTlfdmogsmj0B0rapdJ55nixCLxghUfWbkK7CtSu02j34h67fjZN+dM7qAalokGbuuHMO6n1ssCT64mGrhKnYvmeTT4bEx4VHPH0FmipfiE5NVJXZdpHV" Jul 20 11:02:40 volumio go-librespot[20988]: time="2024-07-20T11:02:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:40 volumio go-librespot[20988]: time="2024-07-20T11:02:40+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:41 volumio go-librespot[20988]: time="2024-07-20T11:02:41+02:00" level=debug msg="completed challenge" Jul 20 11:02:41 volumio go-librespot[20988]: time="2024-07-20T11:02:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:43 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:43 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 987. Jul 20 11:02:44 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:44 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:44 volumio go-librespot[20996]: Librespot-go daemon starting... Jul 20 11:02:44 volumio go-librespot[20996]: time="2024-07-20T11:02:44+02:00" level=info msg="generated new device id: e5f78e6d9825cebf76d516d9c8e483b70369e13d" Jul 20 11:02:44 volumio go-librespot[20996]: time="2024-07-20T11:02:44+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:44 volumio go-librespot[20996]: time="2024-07-20T11:02:44+02:00" level=debug msg="obtained new client token: AACWTvsyAuPn1MvDYZj8hgeyOvZjpGiKrAOn1/iA5+T5Ek4MeEyp3bmynkTVNxVJdHaPfEx5uRvywdTEmlxL7LKuURgYSpNabUde+hZ5IY1hXCBsis4GCIJi4CUZUvnPfazwbWpdI0KJIfH7DnP7LSL+o4qTfCbLOJCbwGzPrGlSkhtkX7CO9gS88ThWCv36siVZecOaX0tPr7V0inwaULLsVFn+Rb9zEjIxCEC9JA63sWrOT2NwsaMX1/5086+d" Jul 20 11:02:45 volumio go-librespot[20996]: time="2024-07-20T11:02:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:45 volumio go-librespot[20996]: time="2024-07-20T11:02:45+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:45 volumio go-librespot[20996]: time="2024-07-20T11:02:45+02:00" level=debug msg="completed challenge" Jul 20 11:02:45 volumio go-librespot[20996]: time="2024-07-20T11:02:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:46 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:46 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 988. Jul 20 11:02:48 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:48 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:48 volumio go-librespot[21004]: Librespot-go daemon starting... Jul 20 11:02:48 volumio go-librespot[21004]: time="2024-07-20T11:02:48+02:00" level=info msg="generated new device id: 88dd3d4edad5e18c54867ddf3ec5e93fd0a91602" Jul 20 11:02:48 volumio go-librespot[21004]: time="2024-07-20T11:02:48+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=debug msg="obtained new client token: AAD0rSnSzaDC6xHohbfAyzmYILBPIHxmPyNJ+9/vwatgRRcrEmn0O4/KVZbPQiqWqLgiJ7nEr7Xj6tkVrPDJFGJJI+k7dY6/M64MCDPnPjZ3kp/i5BxhgGza6piKxz/y/E9C70AIzUT4SBxc/rm8aeOqV7MMzJmMSwVuDmmpNzkT3AUjH84TXPYLdi7YlDa4/AxGFUen+v6ksr9Eik4YcbWeHTfJuS7VMx3s3U8BL1APciagWlNeF0Oh8usO7g==" Jul 20 11:02:49 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=debug msg="new websocket client" Jul 20 11:02:49 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=debug msg="completed challenge" Jul 20 11:02:49 volumio go-librespot[21004]: time="2024-07-20T11:02:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:49 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:02:52 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:02:52 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:52 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:02:52 volumio volumio[752]: (node:752) 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: 1488) Jul 20 11:02:52 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:02:52 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:02:52 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:02:52 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:52 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 989. Jul 20 11:02:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:53 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:53 volumio go-librespot[21011]: Librespot-go daemon starting... Jul 20 11:02:53 volumio go-librespot[21011]: time="2024-07-20T11:02:53+02:00" level=info msg="generated new device id: d2c2b5dd7efb4af860dbe85c93aa25b7a4ce64e5" Jul 20 11:02:53 volumio go-librespot[21011]: time="2024-07-20T11:02:53+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:53 volumio go-librespot[21011]: time="2024-07-20T11:02:53+02:00" level=debug msg="obtained new client token: AADsytKnI5sVgx0X/62Uqvdp0mSnBKddIEf5LzAFB3qRWR7Ar48WwB4hzPFFGCoGbZb1OkgE7fmrZ6onEjzj4cnV+U3/K/sIv1YJ5GzS6+Wqdjf4IsOGOwEFooxODeZQvQpPe36MZrmyGmlQm20B0ddGkPE5ho8nw8toU8lnsbyQOt8cdxmb0Xbi9U0mgIrcQpOCrLdrYLo0wzbBjpqlmPg2mAqYiUOayBkjL8d7MIWSWfPOShUtinnIWF0UD4pR" Jul 20 11:02:53 volumio go-librespot[21011]: time="2024-07-20T11:02:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:53 volumio go-librespot[21011]: time="2024-07-20T11:02:53+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:54 volumio go-librespot[21011]: time="2024-07-20T11:02:54+02:00" level=debug msg="completed challenge" Jul 20 11:02:54 volumio go-librespot[21011]: time="2024-07-20T11:02:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:55 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:55 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:02:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:02:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 990. Jul 20 11:02:57 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:02:57 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:02:57 volumio go-librespot[21057]: Librespot-go daemon starting... Jul 20 11:02:57 volumio go-librespot[21057]: time="2024-07-20T11:02:57+02:00" level=info msg="generated new device id: d1771a6914aa6e27877d44a17c54fc22b1340d9d" Jul 20 11:02:57 volumio go-librespot[21057]: time="2024-07-20T11:02:57+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:02:57 volumio go-librespot[21057]: time="2024-07-20T11:02:57+02:00" level=debug msg="obtained new client token: AABc4zB8wDnvn3vq+14W7hyR6HAbGZ29KyUHqWNIWuTKsMFIzFS9QsQrX9G8Z/1mulNLvH/LSuisgGJvDKXMwarJ0UCFTUi63geZYTs4bPyX9eU50tDd/ZBpKaUx9HOKMe5E05dDijzDAOJRFWRihL7ZMDM75GTWJmVaaLSe4EXRwC7BAgNs7javwiXqJOTduIC6mN8Eadu4v4Ig1qX/wfXjuIfWSz3FXfteNce/uIRS+yXLDNvSvS2dWH9QIw9A" Jul 20 11:02:57 volumio go-librespot[21057]: time="2024-07-20T11:02:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:02:57 volumio go-librespot[21057]: time="2024-07-20T11:02:57+02:00" level=debug msg="completed keyexchange" Jul 20 11:02:58 volumio go-librespot[21057]: time="2024-07-20T11:02:58+02:00" level=debug msg="completed challenge" Jul 20 11:02:58 volumio go-librespot[21057]: time="2024-07-20T11:02:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:02:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:02:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:02:58 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:02:58 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 991. Jul 20 11:03:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:01 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:01 volumio go-librespot[21065]: Librespot-go daemon starting... Jul 20 11:03:01 volumio go-librespot[21065]: time="2024-07-20T11:03:01+02:00" level=info msg="generated new device id: a2587b5f1dbc925e5a75a7cce28b78ef0f35d68d" Jul 20 11:03:01 volumio go-librespot[21065]: time="2024-07-20T11:03:01+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:01 volumio go-librespot[21065]: time="2024-07-20T11:03:01+02:00" level=debug msg="obtained new client token: AAAm6Y7aczxLPCWeRjYSNInMUdlkxUng7pyidfWBM0t99wfyDvLsRMGGVXSYzUMsTGd0zRMqr3NMC0yb1BzQLBOP+mkpq3EMa4jMsNfQrw1evwFjSUJhA06WSrVW3x6iQvExeINcg9sf53MI9T6oNEM2BmtlC2rmdlsXWNtImLr386VSp4rIS+c8yUvjMgy2Yu9h/y3i8PSTilhEPBcKVrs5/unxnQ8GATofb+XU6VInrprGidqzx6n5PN+aUdlN" Jul 20 11:03:01 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:01 volumio go-librespot[21065]: time="2024-07-20T11:03:01+02:00" level=debug msg="new websocket client" Jul 20 11:03:01 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:03:02 volumio go-librespot[21065]: time="2024-07-20T11:03:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:03:02 volumio go-librespot[21065]: time="2024-07-20T11:03:02+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:02 volumio go-librespot[21065]: time="2024-07-20T11:03:02+02:00" level=debug msg="completed challenge" Jul 20 11:03:02 volumio go-librespot[21065]: time="2024-07-20T11:03:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:02 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:03:05 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:03:05 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:05 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:03:05 volumio volumio[752]: (node:752) 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: 1489) Jul 20 11:03:05 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:03:05 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:05 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:05 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:05 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 992. Jul 20 11:03:05 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:05 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:05 volumio go-librespot[21072]: Librespot-go daemon starting... Jul 20 11:03:05 volumio go-librespot[21072]: time="2024-07-20T11:03:05+02:00" level=info msg="generated new device id: 07b461f22bef23c414f301d7e6d11009e0f71ab2" Jul 20 11:03:05 volumio go-librespot[21072]: time="2024-07-20T11:03:05+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:06 volumio go-librespot[21072]: time="2024-07-20T11:03:06+02:00" level=debug msg="obtained new client token: AAApHGFtE0sdpKnwn/OsUFPtlae5jLew18/AWGSRpBupvLN61V8BI/Zmjezx4JOUXMMAgQrwaroKpxlRBwhZXvs3Ngpa3QG/NvqcAKvzZwnvOGpQtQzUZ5RXFI+LsB/aVab1P+ulbML0Bzi2lxn71BPvecpdXSddoV6UyeTd9/5hgvHq9w/tz7cqc9cLKZBP5uaJ2yK5nyxXlj1Hwx5xla8X4XUh7m2mTMoq4O931ZTPxHVdajV6cIiqnvNaYw==" Jul 20 11:03:06 volumio go-librespot[21072]: time="2024-07-20T11:03:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:06 volumio go-librespot[21072]: time="2024-07-20T11:03:06+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:06 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 11:03:06 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 11:03:06 volumio volumio[752]: info: Discovery: Getting this device information Jul 20 11:03:06 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:06 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:06 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 11:03:06 volumio go-librespot[21072]: time="2024-07-20T11:03:06+02:00" level=debug msg="completed challenge" Jul 20 11:03:06 volumio go-librespot[21072]: time="2024-07-20T11:03:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:08 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:08 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:08 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 20 11:03:08 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 20 11:03:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 993. Jul 20 11:03:10 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:10 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:10 volumio go-librespot[21079]: Librespot-go daemon starting... Jul 20 11:03:10 volumio go-librespot[21079]: time="2024-07-20T11:03:10+02:00" level=info msg="generated new device id: c581634fe5d37e24bcc3726a8bec8e024af7d3b0" Jul 20 11:03:10 volumio go-librespot[21079]: time="2024-07-20T11:03:10+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:10 volumio go-librespot[21079]: time="2024-07-20T11:03:10+02:00" level=debug msg="obtained new client token: AABMMdyS1VPfBvuCKOOW1nxmJmuYAdCniCXPwbRZmHHzV8qSlubWaQi93fb7k8VriYIXBy6lCj4Ga2AqRFJ37Qs5nh7b8W2jAwzzSomPsfvwwLO2jMM5A418ViZxMOSOEXD8vSl62dyX8JMcoDiWGM7dx2y8/aVoizkMxfwjXe9KvShb4U3vTN/9PnK+3skBThx/a89YSxVgpJAS7BOCxwbL30O3kzh8H1Ur99YqKkB/ILDPZ+9IzO5AAIcyTAq4" Jul 20 11:03:10 volumio go-librespot[21079]: time="2024-07-20T11:03:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:10 volumio go-librespot[21079]: time="2024-07-20T11:03:10+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:11 volumio go-librespot[21079]: time="2024-07-20T11:03:11+02:00" level=debug msg="completed challenge" Jul 20 11:03:11 volumio go-librespot[21079]: time="2024-07-20T11:03:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:11 volumio sudo[21087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 20 11:03:11 volumio sudo[21087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 11:03:11 volumio sudo[21089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 11:03:11 volumio sudo[21089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 11:03:11 volumio sudo[21087]: pam_unix(sudo:session): session closed for user root Jul 20 11:03:11 volumio sudo[21089]: pam_unix(sudo:session): session closed for user root Jul 20 11:03:11 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:11 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:11 volumio volumio[752]: verbose: New Socket.io Connection to 192.168.50.10 from 192.168.50.69 UA: okhttp/4.9.2 Total Clients: 6 Jul 20 11:03:11 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::volumioGetVisibleSources Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:12 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 20 11:03:12 volumio volumio[752]: info: Received Get System Info Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 11:03:12 volumio volumio[752]: info: Discovery: Getting this device information Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:12 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:12 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:12 volumio volumio[752]: info: Listing playlists Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 11:03:12 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 20 11:03:13 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 20 11:03:13 volumio volumio[752]: info: Received Get System Info Jul 20 11:03:13 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 11:03:13 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 11:03:13 volumio volumio[752]: info: Discovery: Getting this device information Jul 20 11:03:13 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:13 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:13 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 11:03:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 994. Jul 20 11:03:14 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:14 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:14 volumio go-librespot[21093]: Librespot-go daemon starting... Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=info msg="generated new device id: a7675926931c6db76c2750d93c20d834a3f2341c" Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::volumioGetQueue Jul 20 11:03:14 volumio volumio[752]: info: CoreStateMachine::getQueue Jul 20 11:03:14 volumio volumio[752]: info: CorePlayQueue::getQueue Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 20 11:03:14 volumio volumio[752]: info: Received Get System Info Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 11:03:14 volumio volumio[752]: info: Discovery: Getting this device information Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:14 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:14 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=debug msg="obtained new client token: AABflBlz9Kuwx3121JwvV8Ewm7q9B9ECwY29QQAkkOI8DREi5eD9Qlh8efidW+2zZmaPD0bFdYWnve0yUEb1VHtfoQtjBFdtriakcB8iVKpHRQTxSvXN2y/tp2krh8w3T8gFi62DHpXKKmg9uFPWAkpswSoFL7eJpgk84tkUDuakZ3CxSuaAqEURxtHfMWzvYfmm0AlfXjzOlXh5sGO159zPAVN9R3BIYjGSbS81V3O3TjX/hkCEPxrJlXy7nkMA" Jul 20 11:03:14 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=debug msg="new websocket client" Jul 20 11:03:14 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:14 volumio go-librespot[21093]: time="2024-07-20T11:03:14+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:15 volumio go-librespot[21093]: time="2024-07-20T11:03:15+02:00" level=debug msg="completed challenge" Jul 20 11:03:15 volumio go-librespot[21093]: time="2024-07-20T11:03:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:15 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:03:17 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:03:17 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:17 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:03:17 volumio volumio[752]: (node:752) 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: 1490) Jul 20 11:03:17 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:03:17 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:17 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:18 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:18 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 995. Jul 20 11:03:18 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:18 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:18 volumio go-librespot[21100]: Librespot-go daemon starting... Jul 20 11:03:18 volumio go-librespot[21100]: time="2024-07-20T11:03:18+02:00" level=info msg="generated new device id: 0910a23021628c215cfd81004ea0d6d16a3294f8" Jul 20 11:03:18 volumio go-librespot[21100]: time="2024-07-20T11:03:18+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:18 volumio go-librespot[21100]: time="2024-07-20T11:03:18+02:00" level=debug msg="obtained new client token: AAAc8AY7+EwS2R9xj+6rBIhuHSJcnqVhjMN4ztNsCMz/QRfxW91i4M0qbssKfCjGX8sinybw9Wb2RtUgBtJ2oUXSKdbNpMcvM/iJTwGDxm88xT52dG+h7FvQSb2or52aKkLNkqwK7H3JF0yNIU1WekQ+NF0A6+P29oEXUFxtOBK+QFHzlNoGj7SO2EQDwhgPS6dqg8aHDa2EMKuGW/fzohPh8QGK3LY4niHc3lz04RBR/VnbSYM4OFTLQYAiRbQh" Jul 20 11:03:19 volumio go-librespot[21100]: time="2024-07-20T11:03:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:19 volumio go-librespot[21100]: time="2024-07-20T11:03:19+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:19 volumio go-librespot[21100]: time="2024-07-20T11:03:19+02:00" level=debug msg="completed challenge" Jul 20 11:03:19 volumio go-librespot[21100]: time="2024-07-20T11:03:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:21 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:21 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 996. Jul 20 11:03:22 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:22 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:22 volumio go-librespot[21107]: Librespot-go daemon starting... Jul 20 11:03:22 volumio go-librespot[21107]: time="2024-07-20T11:03:22+02:00" level=info msg="generated new device id: b6766e5d348a53d673eeaecb3a25c83fd5c3a62f" Jul 20 11:03:22 volumio go-librespot[21107]: time="2024-07-20T11:03:22+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:23 volumio go-librespot[21107]: time="2024-07-20T11:03:23+02:00" level=debug msg="obtained new client token: AAD25fz8NNHHRrGPw7vY+ihRSUVrVCXlcRIGIPhsgSbH3i3RGEQy15F5p3u683BJJD5hMqrboFceZ6bi5IbPdUV3I4hhcahfUeEb3px4/7Xh1ZV8jMx/04mbMuYf+b7g4tsJPSNXc89AY84sUMIZ2azAzQBsrIvvvK18YrPOWkJEu2vvQvVSnUSWtn1evJ1BOgdTHJweaMYT1S/R/NeOseX22CK/Kv+RUPAKTf2f1M9k8ydCUS4Cjy0w8JSrXw==" Jul 20 11:03:23 volumio go-librespot[21107]: time="2024-07-20T11:03:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:03:23 volumio go-librespot[21107]: time="2024-07-20T11:03:23+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:23 volumio go-librespot[21107]: time="2024-07-20T11:03:23+02:00" level=debug msg="completed challenge" Jul 20 11:03:23 volumio go-librespot[21107]: time="2024-07-20T11:03:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:24 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:24 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 997. Jul 20 11:03:27 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:27 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:27 volumio go-librespot[21114]: Librespot-go daemon starting... Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=info msg="generated new device id: 48ec9113080ea9a38c690f43017317e70240b404" Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=debug msg="obtained new client token: AABZGJ+YgmIlm7QGpqGUBHPB9pgCmhst/aH02Iegg86dTw/5RmZv0bXl+G43vQWzcQpX81TiU0RgonkLHYXSNniZpKA2tW8oC58Np89QTO5yS/zxaVWRtaItLscR1VtxlJnPP2GbiJWcKSDjJKl4/l3u2nLG5vBtbi8MpUZzjj3LCQxOr2bCIWakrp6L/xNN3p0SAX/8vC2pVROXEYNKi9CGzBQQRlUZdQo04PpMtvNsbS19Yp4zjDzG91xeKbg3" Jul 20 11:03:27 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=debug msg="new websocket client" Jul 20 11:03:27 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:03:27 volumio go-librespot[21114]: time="2024-07-20T11:03:27+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:28 volumio go-librespot[21114]: time="2024-07-20T11:03:28+02:00" level=debug msg="completed challenge" Jul 20 11:03:28 volumio go-librespot[21114]: time="2024-07-20T11:03:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:28 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 11:03:29 volumio volumio[752]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 20 11:03:29 volumio volumio[752]: info: Received Get System Version Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 11:03:29 volumio volumio[752]: info: Received Get System Info Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 11:03:29 volumio volumio[752]: info: Discovery: Getting this device information Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:29 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:29 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 11:03:30 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:03:30 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:30 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:03:30 volumio volumio[752]: (node:752) 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: 1491) Jul 20 11:03:30 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:03:30 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:30 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:31 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:31 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 998. Jul 20 11:03:31 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:31 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:31 volumio go-librespot[21122]: Librespot-go daemon starting... Jul 20 11:03:31 volumio go-librespot[21122]: time="2024-07-20T11:03:31+02:00" level=info msg="generated new device id: b0a321f451d87bda9a6a556a46a258c4c3acdb94" Jul 20 11:03:31 volumio go-librespot[21122]: time="2024-07-20T11:03:31+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:31 volumio go-librespot[21122]: time="2024-07-20T11:03:31+02:00" level=debug msg="obtained new client token: AACDNsiBxngreW1zLgKHh2rMKBvWHm2nL2TAWCtbZ2HH0Do+MRa4GZluFp2BHNbZWeKqQ/HAEcVkXRsCqc5s5xEfo6bhSnImKZ4n8qIK0i8qgMpd3kRv7drJFscShkcEyKefiKxUMHU+03ZwHmX+L4txRL4brkwI/lOnQDZ2hc207zMGkJ8p574gJeRS4Iqx25NXWrDzewznDg43WKKqhygbNJRZlX6dwsm5NbAOC/noYZKNIeOsIbD5SzunmNxj" Jul 20 11:03:31 volumio go-librespot[21122]: time="2024-07-20T11:03:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:03:31 volumio go-librespot[21122]: time="2024-07-20T11:03:31+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:32 volumio go-librespot[21122]: time="2024-07-20T11:03:32+02:00" level=debug msg="completed challenge" Jul 20 11:03:32 volumio go-librespot[21122]: time="2024-07-20T11:03:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:34 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:34 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 999. Jul 20 11:03:35 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:35 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:35 volumio go-librespot[21130]: Librespot-go daemon starting... Jul 20 11:03:35 volumio go-librespot[21130]: time="2024-07-20T11:03:35+02:00" level=info msg="generated new device id: c71a257c107ea73850c930922cc0290c6bce4594" Jul 20 11:03:35 volumio go-librespot[21130]: time="2024-07-20T11:03:35+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:35 volumio go-librespot[21130]: time="2024-07-20T11:03:35+02:00" level=debug msg="obtained new client token: AACj4wKZKZYXCqolck6n4V+DEgVdJLHoQm709mGFaQfmgIkbqf0uXGwRNxTYgCU5epX3BX9fLkp0H1ijzlPZtCCMWKX7Yj55c20iZWxNmx4w1maOgfbwssCimdqOsuBGEmiigaWZHR1DNJJBBnhwRZkD+hS3N7tKwytmYWYU9xZSBQctsjWmuYU8qRWA5lyYjjw54mqbOy3ec61B5DBVUOJ9bwqUchEPi7X/+YVdprbQWHuoR+KZTqQ2Nz6lbv4x" Jul 20 11:03:36 volumio go-librespot[21130]: time="2024-07-20T11:03:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:36 volumio go-librespot[21130]: time="2024-07-20T11:03:36+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:36 volumio go-librespot[21130]: time="2024-07-20T11:03:36+02:00" level=debug msg="completed challenge" Jul 20 11:03:36 volumio go-librespot[21130]: time="2024-07-20T11:03:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:37 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:37 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1000. Jul 20 11:03:39 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:39 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:39 volumio go-librespot[21138]: Librespot-go daemon starting... Jul 20 11:03:39 volumio go-librespot[21138]: time="2024-07-20T11:03:39+02:00" level=info msg="generated new device id: 933b508457fb741efd988080aec0dac134faa554" Jul 20 11:03:39 volumio go-librespot[21138]: time="2024-07-20T11:03:39+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=debug msg="obtained new client token: AADPm6J9jNCzQqlOiz/lLZdtnQqC8bK7b1mV2p1+g922zbnGDozCixwHe5IUcSJIl9EslZux55Xf/lfJ60C8HySIH+O9gb4EDudK1JZpm/ZwOU/ucE/CErub6Vqvvr6UsakAw/ICl52IIIa/7zA/ghyhHwQQ359w0Q+IzacwzzuKG49u81WS9wzesGvH8zWfQkaynpup9DCoNOeEKJCsWmQT30qpKH/IPu+fz9WxWN15FwA++hYMGR1m554nIA==" Jul 20 11:03:40 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=debug msg="new websocket client" Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:40 volumio volumio[752]: info: Connection to go-librespot Websocket established Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=debug msg="completed challenge" Jul 20 11:03:40 volumio go-librespot[21138]: time="2024-07-20T11:03:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:40 volumio volumio[752]: info: Connection to go-librespot Websocket closed Jul 20 11:03:43 volumio volumio[752]: info: Getting Spotify volume Jul 20 11:03:43 volumio volumio[752]: (node:752) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:43 volumio volumio[752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 20 11:03:43 volumio volumio[752]: (node:752) 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: 1492) Jul 20 11:03:43 volumio volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jul 20 11:03:43 volumio volumio[752]: info: CoreCommandRouter::volumioGetState Jul 20 11:03:43 volumio volumio[752]: info: CorePlayQueue::getTrack 2 Jul 20 11:03:43 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:43 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1001. Jul 20 11:03:44 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:44 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:44 volumio go-librespot[21145]: Librespot-go daemon starting... Jul 20 11:03:44 volumio go-librespot[21145]: time="2024-07-20T11:03:44+02:00" level=info msg="generated new device id: ae0a3e606cfc2a76844b8696fc7f9a36fd382c37" Jul 20 11:03:44 volumio go-librespot[21145]: time="2024-07-20T11:03:44+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:44 volumio go-librespot[21145]: time="2024-07-20T11:03:44+02:00" level=debug msg="obtained new client token: AADBARwBEWha4A1ryqJ43a2M03sfo+70TIu4j8Vugcvk1xIF7UZmLimwWwtQWq53vJ9LGAdOJ0JGtJhYncslIMsYf6X2YMn5f1fZDWlq/QvRjJR8OEKFnlDAgIY5GbAo6bfYNUlBdxImE5G4NfTibwyEsvJc+8KW5q7fxyxCfYc1cMD6gfw3I9wOHhpjW5lzsJx+y0MYuqiOw2g/pUFxgCS9bpc4mbLu0xlCt6qxSipyyKSQftKqANwRafUZaELS" Jul 20 11:03:44 volumio go-librespot[21145]: time="2024-07-20T11:03:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 20 11:03:44 volumio go-librespot[21145]: time="2024-07-20T11:03:44+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:45 volumio go-librespot[21145]: time="2024-07-20T11:03:45+02:00" level=debug msg="completed challenge" Jul 20 11:03:45 volumio go-librespot[21145]: time="2024-07-20T11:03:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:45 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 20 11:03:45 volumio volumio[752]: info: CURURI: music-library/NAS/Muzyka_NAS/Marillion Jul 20 11:03:45 volumio volumio[752]: info: Preload queue cleared Jul 20 11:03:46 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:46 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1002. Jul 20 11:03:48 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:48 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:48 volumio go-librespot[21153]: Librespot-go daemon starting... Jul 20 11:03:48 volumio go-librespot[21153]: time="2024-07-20T11:03:48+02:00" level=info msg="generated new device id: 8c2cd27fbebc8064a97e270b31a678be5ecb3c00" Jul 20 11:03:48 volumio go-librespot[21153]: time="2024-07-20T11:03:48+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:48 volumio go-librespot[21153]: time="2024-07-20T11:03:48+02:00" level=debug msg="obtained new client token: AAAo018rp2Wtn2evXcaJSPkSM8pVDtti93KtRVs2gcBqrZUmtbNy6qVoJIGvT6c1smm6wy1kbv/RruM+d+sYLL163IkBtYLblpNdCekaxjBispugfk4ibMWkYmg+XYO5DqP/9+88jyWVo71/PB83tYrVxgLIAZEwpeoSXf5vQuYBm1M4c0pvmczKC0jJt0qF/W2WVkIo8JPssUnDgBPBabQh+i64DrokLwR5RvxGaxfphmflZpX02ukTUbILz2Dp" Jul 20 11:03:48 volumio go-librespot[21153]: time="2024-07-20T11:03:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 20 11:03:48 volumio go-librespot[21153]: time="2024-07-20T11:03:48+02:00" level=debug msg="completed keyexchange" Jul 20 11:03:49 volumio go-librespot[21153]: time="2024-07-20T11:03:49+02:00" level=debug msg="completed challenge" Jul 20 11:03:49 volumio go-librespot[21153]: time="2024-07-20T11:03:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 20 11:03:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 20 11:03:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 20 11:03:49 volumio volumio[752]: info: Initializing connection to go-librespot Websocket Jul 20 11:03:49 volumio volumio[752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 20 11:03:50 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 20 11:03:50 volumio volumio[752]: info: CURURI: music-library/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition] Jul 20 11:03:51 volumio volumio[752]: info: Preload queue cleared Jul 20 11:03:51 volumio volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 20 11:03:51 volumio volumio[752]: info: CURURI: music-library/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1 Jul 20 11:03:52 volumio volumio[752]: info: Preload queue cleared Jul 20 11:03:52 volumio volumio[752]: info: Preloading song: music-library/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1/Misplaced Childhood JP.CUE Jul 20 11:03:52 volumio volumio[752]: info: Preloading song: music-library/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1/Misplaced Childhood [24-bit Remastered 2Cd Edition] Cd1.m3u Jul 20 11:03:52 volumio volumio[752]: info: Exploding uri music-library/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1/Misplaced Childhood JP.CUE in service mpd Jul 20 11:03:52 volumio volumio[752]: info: ALBUMART /albumart?cacheid=952&web=Marillion/Misplaced%20Childhood%201985-Marillion/extralarge&path=%2Fmnt%2FNAS%2FMuzyka_NAS%2FMarillion%2F1985%20-%20Misplaced%20Childhood%20%5B24-bit%20Remastered%202Cd%20Edition%5D%2FCd1%2FMisplaced%20Childhood%20JP.CUE&metadata=false Jul 20 11:03:52 volumio volumio[752]: info: URI /mnt/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1/Misplaced Childhood JP.CUE Jul 20 11:03:52 volumio volumio[752]: info: ALBUMART /albumart?cacheid=952&web=Marillion/Misplaced%20Childhood%201985-Marillion/extralarge&path=%2Fmnt%2FNAS%2FMuzyka_NAS%2FMarillion%2F1985%20-%20Misplaced%20Childhood%20%5B24-bit%20Remastered%202Cd%20Edition%5D%2FCd1%2FMisplaced%20Childhood%20JP.CUE&metadata=false Jul 20 11:03:52 volumio volumio[752]: info: URI /mnt/NAS/Muzyka_NAS/Marillion/1985 - Misplaced Childhood [24-bit Remastered 2Cd Edition]/Cd1/Misplaced Childhood JP.CUE Jul 20 11:03:52 volumio volumio[752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 11:03:52 volumio volumio[752]: Error: Unable to resolve or reject the same promise twice Jul 20 11:03:52 volumio volumio[752]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 20 11:03:52 volumio volumio[752]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jul 20 11:03:52 volumio volumio[752]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 20 11:03:52 volumio volumio[752]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 20 11:03:52 volumio volumio[752]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 20 11:03:52 volumio volumio[752]: at Socket.emit (events.js:315:20) Jul 20 11:03:52 volumio volumio[752]: at addChunk (internal/streams/readable.js:309:12) Jul 20 11:03:52 volumio volumio[752]: at readableAddChunk (internal/streams/readable.js:280:11) Jul 20 11:03:52 volumio volumio[752]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jul 20 11:03:52 volumio volumio[752]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 20 11:03:52 volumio volumio[752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 11:03:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 20 11:03:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1003. Jul 20 11:03:52 volumio systemd[1]: Stopped go-librespot Daemon. Jul 20 11:03:52 volumio systemd[1]: Started go-librespot Daemon. Jul 20 11:03:52 volumio go-librespot[21170]: Librespot-go daemon starting... Jul 20 11:03:52 volumio go-librespot[21170]: time="2024-07-20T11:03:52+02:00" level=info msg="generated new device id: 05318017cb1f32c9667de24a6c7a8f397ae48f50" Jul 20 11:03:52 volumio go-librespot[21170]: time="2024-07-20T11:03:52+02:00" level=debug msg="stored credentials found for cspxgxr4w2nqbsxykzro5zm83" Jul 20 11:03:52 volumio sudo[21178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-20 11:02 Jul 20 11:03:52 volumio sudo[21178]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"