-- 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"