-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2024-07-15 07:29:57 CEST. -- Jul 15 07:28:00 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:00 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jul 15 07:28:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:01 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:01 volumio go-librespot[1407]: Librespot-go daemon starting... Jul 15 07:28:01 volumio go-librespot[1407]: time="2024-07-15T07:28:01+02:00" level=info msg="generated new device id: 138f5492c257b3ae3442233c5e20902a543084a8" Jul 15 07:28:01 volumio go-librespot[1407]: time="2024-07-15T07:28:01+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:01 volumio go-librespot[1407]: time="2024-07-15T07:28:01+02:00" level=debug msg="obtained new client token: AADJ+3OIu1Uou/ATqt7Hbz1DkQNhQPvSj4mPIfKZAIRd0agThHKO5TeFi+CQvdshhuKziEQjZEluYjKygbn25BI9e5HUvSnTlFl5F9U2XvgJjR1lFPGZvzhJ/8tNdlIF4XNw3v/zKXQXr+clLfKcydqAO5QvGQlGu+tn+yHS+m5epSBdPQlIFWNL2m67Is1/GLFWvyqZIhcN3Fkb3/+FwiQLDVvCPgzF/+YaIYXwqaXlgkbWTd+I1YSsuTo7ZA==" Jul 15 07:28:01 volumio go-librespot[1407]: time="2024-07-15T07:28: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:28:01 volumio go-librespot[1407]: time="2024-07-15T07:28:01+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:02 volumio go-librespot[1407]: time="2024-07-15T07:28:02+02:00" level=debug msg="completed challenge" Jul 15 07:28:02 volumio go-librespot[1407]: time="2024-07-15T07:28:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:03 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:03 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jul 15 07:28:05 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:05 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:05 volumio go-librespot[1416]: Librespot-go daemon starting... Jul 15 07:28:05 volumio go-librespot[1416]: time="2024-07-15T07:28:05+02:00" level=info msg="generated new device id: 7cb7a60060f64ac864594d1111ea65d9fec5c30e" Jul 15 07:28:05 volumio go-librespot[1416]: time="2024-07-15T07:28:05+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:05 volumio go-librespot[1416]: time="2024-07-15T07:28:05+02:00" level=debug msg="obtained new client token: AAD2ZLqJvjUssezzL4KKxpc8qS6IcOINWxwmTCE7dk1qXvft15EZE1aGEbd0VxyzzE///tJO6z+eIm8h7+jy9uuPOn9JcxaQ1CZy1KlLe6zpEt6avHMc4Hkxe5Iri1OyYsjltg1Va47uG/h73IO5a2z5cBxqKNnLi33e6oE7IzK76GOXbWxb2yb9GnJ09THYpvtVO5Ipac7XEV+q0goCM15ay+edFlJPT3LiSqe/hYaPl4mhUvQ5GLy7e/h2ng==" Jul 15 07:28:05 volumio go-librespot[1416]: time="2024-07-15T07:28: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 15 07:28:05 volumio go-librespot[1416]: time="2024-07-15T07:28:05+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:06 volumio go-librespot[1416]: time="2024-07-15T07:28:06+02:00" level=debug msg="completed challenge" Jul 15 07:28:06 volumio go-librespot[1416]: time="2024-07-15T07:28:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:06 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:06 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:06 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:06 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:09 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Jul 15 07:28:09 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:09 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:09 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:09 volumio go-librespot[1424]: Librespot-go daemon starting... Jul 15 07:28:09 volumio go-librespot[1424]: time="2024-07-15T07:28:09+02:00" level=info msg="generated new device id: a1c9b613cf010e2c8b7266516d98766a040a79f2" Jul 15 07:28:09 volumio go-librespot[1424]: time="2024-07-15T07:28:09+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:09 volumio go-librespot[1424]: time="2024-07-15T07:28:09+02:00" level=debug msg="obtained new client token: AAAJbkoQ8B+9mVrVk8udCC4jJr51ih/Q4huZSwLaMAeSxKRtsSdOF1/wgXom1vJCPJAU8IPRIDvnV0z2o2PvKc4cAGtg+5Y1pUFvSmBJTIYcTUvCPFfzSwCCTHKzCmHvrIR0QiMv2oJNm/irsidSuaTPh/V2nJkQ/b3fOTQ08RnVfkMcK7nNXfSqcvnv2zFdaqS6LtnaVd901H1RZ+je9YhEyd5S432RXuPHLezDe1OWg8zhLvG/iifp3wwcYA==" Jul 15 07:28:09 volumio go-librespot[1424]: time="2024-07-15T07:28:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:28:09 volumio go-librespot[1424]: time="2024-07-15T07:28:09+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:10 volumio go-librespot[1424]: time="2024-07-15T07:28:10+02:00" level=debug msg="completed challenge" Jul 15 07:28:10 volumio go-librespot[1424]: time="2024-07-15T07:28:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:12 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:12 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Jul 15 07:28:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:13 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:13 volumio go-librespot[1432]: Librespot-go daemon starting... Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=info msg="generated new device id: 6a624012e70bceb5c7fc6a2a5f1a72618c998e2b" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=debug msg="obtained new client token: AADG5mpai3tY/4xiMhHTfhRtDz4blLuRBXf3tqq/aoi1NntbCqoPbaRreGfbBWG1+wMXyJb7t+1gZa64aZrgG+0ZUxhhbUsWVIYFM7oIPsIu36zJquMI9NSX6SNCE84NtumBhCzlOrK5WP7yxUHN8WkMZLKczXwSz9GDmbuSw7f3Y+zJd/Yi2v8cPEI3Kb1DNqkGf3JzomtWM+ExLg9rnZnKDitsjch58oiQ/hgdidwELQf+toMsXwbJpAFPKg==" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=debug msg="completed challenge" Jul 15 07:28:13 volumio go-librespot[1432]: time="2024-07-15T07:28:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:15 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:15 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:16 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:16 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:16 volumio volumio[877]: info: Listing playlists Jul 15 07:28:16 volumio volumio[877]: info: Listing playlists Jul 15 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Jul 15 07:28:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:17 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:17 volumio go-librespot[1440]: Librespot-go daemon starting... Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=info msg="generated new device id: c422e5fde591cb863e0c366e7eb7b56a5687908c" Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=debug msg="obtained new client token: AAC3L/+sWc/y0J2v3lthWtb0DRRCgknQJ9oNZE7kkL1WuWx83nmtvuwgQIZkY1BZzT+ZbdNgNEHR3pWJDCdW/AdntihXpjd10EOGFGqfn/tWtqq3RUO/8Ps2bNW4saGfDJJD+02rjSycJkO78ZaoJz71967rkrW/DWMJvNHmXzyBAaix2EBk4E75LwQACj+1uB2pqwoQtmPD36OwRvoSfNan1j8hQ+COn5qBJ4HfbA5fzK93gCkb6cHXL+yIlw==" Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+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 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=debug msg="completed challenge" Jul 15 07:28:17 volumio go-librespot[1440]: time="2024-07-15T07:28:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:18 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:18 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Jul 15 07:28:21 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:21 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:21 volumio go-librespot[1448]: Librespot-go daemon starting... Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=info msg="generated new device id: b06f0b297b0e0d136a00afd0fd7c7d0a2d6ead64" Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:21 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=debug msg="new websocket client" Jul 15 07:28:21 volumio volumio[877]: info: Connection to go-librespot Websocket established Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=debug msg="obtained new client token: AADONToLQEzzN53m5sdFhYXNQWCSPc66t++Fq3K0BQioVpKuEfo2mZaQEqPnTBJz9mTMJ51CK9wDSnRrOSdhUluKX3eDvfFpdHrzUyTpKRnV/ckkg6bpFp0l+QMIwlxHTieZWgB8Z14tHdySTSxgWnOVusrOdGvpMcirMSduotpB/tNsXNSb17AC4b4fRWZMRMQO7OHO3ZVIb/P7yga/u06L1wegdypjC1uT0CoIEhd5UQvQmJOyatsRJ+NANw==" Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+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 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=debug msg="completed challenge" Jul 15 07:28:21 volumio go-librespot[1448]: time="2024-07-15T07:28:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:21 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jul 15 07:28:24 volumio volumio[877]: info: Getting Spotify volume Jul 15 07:28:24 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:24 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 15 07:28:24 volumio volumio[877]: (node:877) 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: 6) Jul 15 07:28:24 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 15 07:28:24 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:24 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:24 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jul 15 07:28:24 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:24 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Jul 15 07:28:25 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:25 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:25 volumio go-librespot[1456]: Librespot-go daemon starting... Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=info msg="generated new device id: 2101aece8f6a25e1b42a5fcda45f6a845339abfd" Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=debug msg="obtained new client token: AADtuo6OCIOBQxV2fFuJ7tUAqCQS86AsBxIP0NQsGKq+xWRcey+fUDOzhpZpRefBY2Zjn/GHhbwENXLB61k9KQB/XbzGVUTj02oUrGf1CPr611hIIRg5iM1GtANb4HGSnT9WiDtRLm8iW6Bb38Sw3Q1onMtQ7MKLHDIQOM4ALa1bWm9GENUjVZU+SyKsOTgN4wQtWWZDC4GA791CRVCDxOgvd4J+7Qj32+cjBIAMwP/7mOuaaV0tKaHBDaSDqg==" Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+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 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=debug msg="completed challenge" Jul 15 07:28:25 volumio go-librespot[1456]: time="2024-07-15T07:28:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:26 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:26 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:27 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:27 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Jul 15 07:28:29 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:29 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:29 volumio go-librespot[1464]: Librespot-go daemon starting... Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=info msg="generated new device id: b11f3f524099a6a5bb22224155b18ff22e251e1b" Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=debug msg="obtained new client token: AAAGVBSuZwyu5/WNZ9H6GLI9X2R2gIjNghHBBzElV0hWvmq1xMRnL1e9ID88VdrZP3rpTaykXQb+/F47q+csd/iMfjuOzlDeyqB8uVoiJ95eZ3IrfM9lNXHcT+HiYrLNqrxb3XfJsSSo59PqzPo0B5vkSXz4zcnmh71cTkI+uZcbOxPOF6WTNdJKHxiCACBjPPZnzkmT1pk45TuHcwELK2UJ0azmrN5KqIJinuA799sck20HgVdlDOgmtnR7Vg==" Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+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 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:29 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=debug msg="completed challenge" Jul 15 07:28:29 volumio go-librespot[1464]: time="2024-07-15T07:28:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:30 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:30 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Jul 15 07:28:33 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:33 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:33 volumio go-librespot[1472]: Librespot-go daemon starting... Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=info msg="generated new device id: f9968c5ffe060b255891d3c8a487c7d2601fe8dc" Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=debug msg="obtained new client token: AABOGS8RKGDTDOvbVvg5vak0A7owZV3pCUy0Afv8r9ppqhEI8B7qi8ShhBK+WEp3Waap5gqnU9EjjehpbA4+E49ZmAtDtC39jRPK3D2xoPf4OgmdBwwdHHN1j4oImofdtqCI/aLknThEObVITHqGlwePGZKiKwpjJguAWj1UuRA5dgXQPGaRSr9/amEqn9GXAcsm/+ofAABWX4uAlEHzZxYxsVKqVHE/kf1XC0P8ZwYYVeK5d15kT06xlJF5+A==" Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+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 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=debug msg="completed challenge" Jul 15 07:28:33 volumio go-librespot[1472]: time="2024-07-15T07:28:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:33 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:33 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:36 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:36 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:36 volumio volumio[877]: info: Listing playlists Jul 15 07:28:36 volumio volumio[877]: info: Listing playlists Jul 15 07:28:36 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:36 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Jul 15 07:28:37 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:37 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:37 volumio go-librespot[1480]: Librespot-go daemon starting... Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=info msg="generated new device id: bacd93e54ab623fc169280eba14f7e62d21d9ea5" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=debug msg="obtained new client token: AAC4GPZDyNckbfvEQ7DsDpopA/GmT1d2ZxLmkGsIY5ACdHG1komHD1jkAEnvXvXt+coIQJCcic3vKjQi/iFa1yINdUqCtTXhmjPb3DfVwp7XHQ6bJknBgwvxiKI5rtYdbzLUp2wyBUSN6HxKZMfhpL/ghvpE9NoCQJw+USdmRCYumn7l+BFwJ7ySG1gFuJsMj0t45KG3r+J9V59mJaWCsAyaEJ6h32btqKxHEeTB+xy39FjUbNp3n+EjhQljug==" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=debug msg="completed challenge" Jul 15 07:28:37 volumio go-librespot[1480]: time="2024-07-15T07:28:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:39 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:39 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Jul 15 07:28:41 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:41 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:41 volumio go-librespot[1488]: Librespot-go daemon starting... Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=info msg="generated new device id: 021f530795569fa0ccc52c40455dc11fc317b3c5" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=debug msg="obtained new client token: AADNNhIiGGFSJ51bmFLLIAjIfs3yiyNWDGfUQRB9ftJ+CrqvjxnziH3m9i2ZmehlDAN9TFPwN5v67dLEkWwDaI+JJkL7oeipeOvOtumyzXsGWJCoZ42P2Tlp092AwZH8q2Q28r8m3jUq2L9azGbW2DmhqS3t5I6jYJ84S+OcIGndpL6VcIK0dXR5OmGZhzAJwZpm/m6g0D2QU/0nHGoiSnhDvDC7ILxrEBWYb6wGrzUTICm2AvkdXCT9Ogf7bQ==" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=debug msg="completed challenge" Jul 15 07:28:41 volumio go-librespot[1488]: time="2024-07-15T07:28:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:42 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:42 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Jul 15 07:28:45 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:45 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:45 volumio go-librespot[1534]: Librespot-go daemon starting... Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=info msg="generated new device id: 5ad1daa3bca33411779099eae3eeab05e79118ff" Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=debug msg="obtained new client token: AAAF7GmOX9ieO4bZNVOvCPza/FnvyPnT1AXNYdKZ8GOTi89bpedRmoITgY8CnWc0lBl0b+yvGg+lwfQnkVp1QUBNiyj5ps9OlwpdWIc6SnMy3g0mGuenWdGnqCb1FJC54q/GkFUF71NxmfAusmNzF0p6CsRQ5JV16RTgfpu/IsKx+YMI8RXgt9TXfp0Zb2wdCWepLOR9S02jG0JmZ1s12qDYlico9GTQBWUrpMSHGJRUfHtIQNV9N2bYF+ss5A==" Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28: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 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=debug msg="completed challenge" Jul 15 07:28:45 volumio go-librespot[1534]: time="2024-07-15T07:28:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:45 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:45 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:46 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 07:28:47 volumio volumio[877]: info: Discovery: Getting this device information Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:47 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 07:28:47 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.20 UA: unknown Engine version: 3 Transport: websocket Total Clients: 6 Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:47 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 15 07:28:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 15 07:28:48 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:48 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Jul 15 07:28:49 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:49 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:49 volumio go-librespot[1543]: Librespot-go daemon starting... Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=info msg="generated new device id: 3c9615d30e36fb21d6092e8442b7d5a6e7996b8b" Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=debug msg="obtained new client token: AACqooLPfQnJOZFivwvLkWi4AVKgQ7Pf4Jyi3vSryLw7Z+lVOfPY/EJi9272YAyQ4f4dH3O9aRlQMEJVHnRvUJ81s5iQE/Pk6ctzsbM2iek+38jzIHZe9y2Z2hFs0z1xWUqVMjy7LeKWqvGlN4zVE6m8iB9CSPNyb1KQzsezF5bEw7y+LIGAzWZPOBBM/vApExQ+V3sSqvIiKY3G/B6GewJZ7mSeumCWS2G6DByejMDKMUhqAr17P3IaJb4vaQ==" Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:49 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 15 07:28:49 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 07:28:49 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Jul 15 07:28:49 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 15 07:28:49 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 07:28:49 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Jul 15 07:28:49 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:49 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 15 07:28:49 volumio volumio[877]: info: Received Get System Info Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 07:28:49 volumio volumio[877]: info: Discovery: Getting this device information Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:49 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:49 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:49 volumio volumio[877]: info: Listing playlists Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=debug msg="completed challenge" Jul 15 07:28:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 15 07:28:49 volumio go-librespot[1543]: time="2024-07-15T07:28:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 15 07:28:51 volumio volumio[877]: info: Received Get System Info Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 07:28:51 volumio volumio[877]: info: Discovery: Getting this device information Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:51 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 07:28:51 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:51 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 15 07:28:52 volumio volumio[877]: info: Received Get System Info Jul 15 07:28:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 07:28:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 07:28:52 volumio volumio[877]: info: Discovery: Getting this device information Jul 15 07:28:52 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:52 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Jul 15 07:28:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:53 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:53 volumio go-librespot[1558]: Librespot-go daemon starting... Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=info msg="generated new device id: ff589b6b54e42c46603d56970d7b2f1a9a9e705d" Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=debug msg="obtained new client token: AAAIufEFXqVVLzULWwsGvh3/lU+3w7K9ZtIDscf1pgG24ygDZD+ebZydcTvcB5SLHXXb33C8EtK15r+bu51hN8ri02PrTbrT/oofVG5xc+wzOfLTdEdB208QpDmhyaw0MBYWDDI1TAhqZRV6P1VysA6inbRGu9c6fIOje9YWLZjBPLS8LbJdufldUYR/FAxPLwfu7NoFK+QyOFlV28HYX3Ej0gx4zNTqaYyKB2xXItMpdovHxbQvEbKB4CD7HQ==" Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 07:28:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=debug msg="completed challenge" Jul 15 07:28:53 volumio go-librespot[1558]: time="2024-07-15T07:28:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:53 volumio volumio[877]: Disk list : [] Jul 15 07:28:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 07:28:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 07:28:54 volumio volumio[877]: info: Received Get System Version Jul 15 07:28:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 07:28:54 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:54 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:28:56 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:28:56 volumio volumio[877]: info: Listing playlists Jul 15 07:28:56 volumio volumio[877]: info: Listing playlists Jul 15 07:28:56 volumio volumio-remote-updater[665]: No test mode Jul 15 07:28:56 volumio volumio-remote-updater[665]: No alpha test mode Jul 15 07:28:56 volumio volumio[877]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 15 07:28:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 15 07:28:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:28:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Jul 15 07:28:57 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:28:57 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:28:57 volumio go-librespot[1581]: Librespot-go daemon starting... Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=info msg="generated new device id: 64bbad066b9940229d18c6a82c651076b0682898" Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=debug msg="obtained new client token: AAC1YQXCDD12U63T0TynmziM2vTIWFi1nHd/YdMwxWgxtdm6MJ7Dvnb3i1V3s3EyBr/Qu2onyYH5uNSU2SGnNwgVqhsSbXalMMlFFOxrOGCoifDMFx7VfRkbjpuQGDeb0Rt3yO1ZXSFMmRHNKeTJ/0GVFnqg2hb9LLuZ+N7238sgGyYPNvGnZ5tzpnnSmO0RiqtRheoyPhySK9YgTClzwlj6VaM5s4mfOJkNLbFdbmj9kRB2VfO9YDjbomeraQ==" Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28: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 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=debug msg="completed keyexchange" Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=debug msg="completed challenge" Jul 15 07:28:57 volumio go-librespot[1581]: time="2024-07-15T07:28:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:28:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:28:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:28:57 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:28:57 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:28:59 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent Jul 15 07:28:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 15 07:29:00 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:00 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Jul 15 07:29:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:01 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:01 volumio go-librespot[1589]: Librespot-go daemon starting... Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=info msg="generated new device id: 1754b1224e62298eec5be8d141d18f1afab0f923" Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=debug msg="obtained new client token: AABFoOwM3Y2tzRfb3WdxawOJ8DpGdet4Fj36CkCS41ssvqncq3tiMsTC7sgJQSc0R5eXLHMKMFGqgvmtx7jQZW5+lG0VB9EsDMVQHgB6c7lG5JoGdkoTAOKfxoqZjp3PAubH1+uy0lAV/rvN4nRBTyGc4Nc+as5jmt2pfngQjN3ZkNnhwn2rXjFQAvoCE8uUy22uT0DkfjbBUps+/hlEtkuz4On2Dkomb3RiTh0rmpD+ImPyTHChwC5YaNPv2Q==" Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:01 volumio volumio-remote-updater[665]: No test mode Jul 15 07:29:01 volumio volumio-remote-updater[665]: No alpha test mode Jul 15 07:29:01 volumio volumio[877]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 15 07:29:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=debug msg="completed challenge" Jul 15 07:29:01 volumio go-librespot[1589]: time="2024-07-15T07:29:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:03 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent Jul 15 07:29:03 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:03 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Jul 15 07:29:05 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:05 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:05 volumio go-librespot[1599]: Librespot-go daemon starting... Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=info msg="generated new device id: 64e283990857a15736814373632335dedea35a5d" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=debug msg="obtained new client token: AAAYO+M7q7pgYU9KNPbJXtTX4+Z382GDJfmPyvMgZTpaOL7k1AYvo+sMIsx+yUHnVNikCyBvPhtJIQhT8pB+oHeLDVViE33cAd/B3bdlGfT4yYfA1ZQa4eipIVB3tXTUeSfukq9u2csiUac+u7P4OJI89wDV6qbaJQcjXOml+AGYMEoWUMG3rE+V/u0av0zQsfaaZ1Fm1VSplHUmkKFHMLoQbriJB+UF/qb/S96OVgOnE16inOKRonV45BHDTA==" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=debug msg="completed challenge" Jul 15 07:29:05 volumio go-librespot[1599]: time="2024-07-15T07:29:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:06 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:06 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:06 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:06 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 07:29:07 volumio volumio[877]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 15 07:29:07 volumio volumio[877]: info: Received Get System Version Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 07:29:07 volumio volumio[877]: info: Received Get System Info Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 07:29:07 volumio volumio[877]: info: Discovery: Getting this device information Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 07:29:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Jul 15 07:29:09 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:09 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:09 volumio go-librespot[1607]: Librespot-go daemon starting... Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=info msg="generated new device id: ff3acf0de7c430e3daa4a18233309a186056d434" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=debug msg="obtained new client token: AAB28OavtZbtKn1CBlSnbGkwL+/1PEoGrzhJJx8Zc8Ekx4z9H+MMXcrW+M1hG2n+hNd7zjp97MPb08FJ7JqZc7GLPXSDPszdcJk2KsYpD0ldPh554xVxk6VAwFqoT/TUw+oTz7Ee0qLRQsmOmmvZkhF90heP3uKIox8e1FJBKEzgYFjO6WIv3w6hTqb7HxCx/LqwlfWkJwYOu+5mDnb42r9hOaaL63V4OdwNFxLi21i4upRiJ2DjF7GCR+oEXQ==" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=debug msg="completed challenge" Jul 15 07:29:09 volumio go-librespot[1607]: time="2024-07-15T07:29:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:09 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:09 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:12 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:12 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Jul 15 07:29:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:13 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:13 volumio go-librespot[1616]: Librespot-go daemon starting... Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=info msg="generated new device id: 8ebbb304395d0f370e421c5f0ae3040df22cf289" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=debug msg="obtained new client token: AABQStymDXpSKlCryPTbIze+7U9UM+Q5BRYddSvbLO6JjLg8Bz2Jbejg/NIDOJtlBHroclHR2I99HfLQNvTInagGAPaQjWcfHlw4I2QkCE0pAL8BT3+fO2B4XYPPaZUJcnZgmz9sbq8vhe4Qm0eQnOeEHPpli2dCfZrBguW/d2AxVzV8V6Yd7rNvCUXeLCAUZZl4Xvf4NJTSVlj2dzCOd5rWS54gUFHwcqIiWxAIaADyZ9kOH/Rj4aFB07ZpaA==" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=debug msg="completed challenge" Jul 15 07:29:13 volumio go-librespot[1616]: time="2024-07-15T07:29:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:15 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:15 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:16 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:16 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:16 volumio volumio[877]: info: Listing playlists Jul 15 07:29:16 volumio volumio[877]: info: Listing playlists Jul 15 07:29:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Jul 15 07:29:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:17 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:17 volumio go-librespot[1624]: Librespot-go daemon starting... Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=info msg="generated new device id: eb4829c41bcdd43fc70a850b6fe7bd0bff9b109d" Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=debug msg="obtained new client token: AABpcOa5fHn5nAxfX2/KPTSyr1rzgP07URtErD9gGeDAE+Ku74FCHZMVisJsaG3kjY3cYex/a/0Ak/ebDmemOHKZS1P7w6ZAKKgmcegTAE+3XuUOg44cYAQ06CXMWBZv19i+ZsG6h+Py72jqT1EQA9vVkdbFOqpF16gCvSBXd/vmsSzYqLiIm1q4s9m7b5wmUQKWgOUgom3F07WAw86V9te4NWflzg3U3wk7RDKJp5xL1rsXzARBoidatuspxQ==" Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+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 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=debug msg="completed challenge" Jul 15 07:29:17 volumio go-librespot[1624]: time="2024-07-15T07:29:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:18 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:18 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Jul 15 07:29:21 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:21 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:21 volumio go-librespot[1632]: Librespot-go daemon starting... Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=info msg="generated new device id: 11d2de39cfe96e11015b1d0914ea665961112d5e" Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=debug msg="obtained new client token: AAC2hSVeJlb98Pd+Qe0XfcpmQepeCOQUxn5IVJ4zkpron9TKRkKLzgsib6VC529SEM073twPpIlML8iG4vpynAEdKjCRtdjeb80uqbwXGUJTNCvLf/ZWjKMUmbUVaa7aOnlBiZbS7+h7UlcSTeW8WLRssBk0YwQqvlC6Os3eFWY30T/aOuwhKlzQ+3thkxN+l3v4XPsrMgmtT4sArXOlh+TQLw5K5/FxWnOkvkCdvMoBWa/oA6PSPfxUY0g5QA==" Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+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 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=debug msg="completed challenge" Jul 15 07:29:21 volumio go-librespot[1632]: time="2024-07-15T07:29:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:22 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:22 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:25 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:25 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Jul 15 07:29:25 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:25 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:25 volumio go-librespot[1640]: Librespot-go daemon starting... Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=info msg="generated new device id: a7670be6d9316666afbc667c337a33020d4fc5c5" Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=debug msg="obtained new client token: AADrlPO6EbxdTa++5ypTggmpMFevfE4DD1CgP107OncvJ5h7aqGIRJxtce4BhScWOeLwv28wCNKDieM51Vi28ZZcwkvyGf+G8SK+WiWW4Fk5bKu5ANDn7PQUCd+cAJ14jwVsoB6CEw8EvgzjNmH2NuoVCaDhzvMFxytycAcy3Ef6lr660eE4F3DF2fqkZUQqdra+rbKkY+7sySjv0bRjLWV342OGzR0pGKFnknFRcB1rgPNpU37vt07O9Ao/gA==" Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+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 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:25 volumio volumio[877]: info: CoreCommandRouter::volumioGetQueue Jul 15 07:29:25 volumio volumio[877]: info: CoreStateMachine::getQueue Jul 15 07:29:25 volumio volumio[877]: info: CorePlayQueue::getQueue Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=debug msg="completed challenge" Jul 15 07:29:25 volumio go-librespot[1640]: time="2024-07-15T07:29:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:26 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:26 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:28 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:28 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Jul 15 07:29:29 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:29 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:29 volumio go-librespot[1648]: Librespot-go daemon starting... Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=info msg="generated new device id: 2dae634e8fd261a35be9293450c4960f9eadaec7" Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=debug msg="obtained new client token: AAC+gQFD3dDiPHRlk+0tbmMaavLLQE/rl0s9+V9W3n67OnjX8T/DNTGmykF2rSD8dc9bIW0Qowx8LZ/nbzMB1S+HFgbxudZAqQzEzlLpHXhWkpNOdoBHfxxv9UAFA5DGYquONoJvURz3UNDkkUOuLHAW0l+Vm8W0vAlh+q1v+bNKpr4K59jgrl0SlMplimg+asqrOOoGmjmK+PY70Kqy3yYBoK4zBipAyHz7VYtHDoDhl2GC9i0mXAv7Oo+tKg==" Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+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 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=debug msg="completed challenge" Jul 15 07:29:29 volumio go-librespot[1648]: time="2024-07-15T07:29:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:31 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:31 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Jul 15 07:29:33 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:33 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:33 volumio go-librespot[1656]: Librespot-go daemon starting... Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=info msg="generated new device id: b8ae2b111592c3be055603464b568545b21a9a17" Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=debug msg="obtained new client token: AACC2GQUWik4jHgj19PIbYWHjitZRLnV7QtYCXHAKHdRsodLAUpEG76ayUMR4QikndQhakoBzO1bTeewzRqV7B7sY1e7oThj1bya6p83A/37NpQoQaVOql54D7bdokhSWbZ+cNRxCRJObPgco08utNGILo39JETWKr3Gh8lVHE5w0l8bVrk1gIUg2hSJwcWhO6SDF8mVmkC/Y3wRoQ8aoN8PvGllYlJkHfOy8Qx4+DyY0cXsOv/gMLalyHuFhg==" Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+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 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=debug msg="completed challenge" Jul 15 07:29:33 volumio go-librespot[1656]: time="2024-07-15T07:29:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:34 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:34 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:36 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:36 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:36 volumio volumio[877]: info: Listing playlists Jul 15 07:29:36 volumio volumio[877]: info: Listing playlists Jul 15 07:29:37 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Jul 15 07:29:37 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:37 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:37 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:37 volumio go-librespot[1665]: Librespot-go daemon starting... Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=info msg="generated new device id: 940eff219b0491e096d819bf6cbcfd44dcf8ccfe" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=debug msg="obtained new client token: AAB0MHoA7FBvAVJpoVxGAp+ZgQmwSnGAZircu5ez/YnOt8LBsla9RgyCehr2FFHcaup3XbgA+CyoH/FNzESw3em7tEbuXXWqUDnKHmM7ls10LUcg3YcfsZfAgb+vDvB8vylMuDSsMwY5PoNppO1qQt8cYnM381VaW+aOUFJsSAVSMdPjfP6nglmQaZupCE3D47QEwpvIlAdeuJ1YyGHEd/4ihvFDS3fLppwfdOWESXHdmqIfDScpio9Iw8w=" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=debug msg="completed challenge" Jul 15 07:29:37 volumio go-librespot[1665]: time="2024-07-15T07:29:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:40 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:40 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Jul 15 07:29:40 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:40 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:40 volumio go-librespot[1674]: Librespot-go daemon starting... Jul 15 07:29:40 volumio go-librespot[1674]: time="2024-07-15T07:29:40+02:00" level=info msg="generated new device id: 0fe7891a40ea7855315a51943a0cc981c11e5b3f" Jul 15 07:29:40 volumio go-librespot[1674]: time="2024-07-15T07:29:40+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:41 volumio go-librespot[1674]: time="2024-07-15T07:29:41+02:00" level=debug msg="obtained new client token: AABhg9Vo/17l0YW71HtxVVol2nzK0sYKeXaYHQfVDk30JvSqCALf9Sq+/mqT30aUklqMQ5qqHmLdkAI4zI/jcF2BnhTkONrScOV2wTYfvPSeJth5/Xc3gQQuBaVXVWXizbr4VMaUul5dpyAvEDEzotXuzCcIqmvUKLl4I5Cij7AfOCWCHCvfBo+EmHokkZ7NkiVcJ+YubMTOD7oM9Wu31J6tfC+YuI4Qp9sVqdUg9n8ihocH1mpwB68rd2U=" Jul 15 07:29:41 volumio go-librespot[1674]: time="2024-07-15T07:29:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:41 volumio go-librespot[1674]: time="2024-07-15T07:29:41+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:41 volumio go-librespot[1674]: time="2024-07-15T07:29:41+02:00" level=debug msg="completed challenge" Jul 15 07:29:41 volumio go-librespot[1674]: time="2024-07-15T07:29:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:43 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:43 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Jul 15 07:29:44 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:44 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:44 volumio go-librespot[1720]: Librespot-go daemon starting... Jul 15 07:29:44 volumio go-librespot[1720]: time="2024-07-15T07:29:44+02:00" level=info msg="generated new device id: 3c09b97ad8c80884ed68c4e7a66a7a20a823fffc" Jul 15 07:29:44 volumio go-librespot[1720]: time="2024-07-15T07:29:44+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:45 volumio go-librespot[1720]: time="2024-07-15T07:29:45+02:00" level=debug msg="obtained new client token: AACrGWposs5Y8v6T+2yqUkd5YSyczYuqdOswHY/tuoHk2+NUjSdRX4TB53fEnBhlOox1pGF5Q6yG2SXSgpdnZag/AUziVLUfH2cLWfZJzaDowYtLn5d/8li3e944/JVlOngp8Nqsa9eCv7l2NZhykfZ2veEwr9Hx6Jlg/PV9SnIfNhynKr8Q479oKLCFCpigZlCUVNrTVtUSBJ/5uNXilDkrq58j7wx/RVP5YLZVEF9/xydlwQtwMvQm9tE=" Jul 15 07:29:45 volumio go-librespot[1720]: time="2024-07-15T07:29: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 15 07:29:45 volumio go-librespot[1720]: time="2024-07-15T07:29:45+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:45 volumio go-librespot[1720]: time="2024-07-15T07:29:45+02:00" level=debug msg="completed challenge" Jul 15 07:29:45 volumio go-librespot[1720]: time="2024-07-15T07:29:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:46 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:46 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:46 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 07:29:47 volumio volumio[877]: info: Preload queue cleared Jul 15 07:29:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jul 15 07:29:48 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:48 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:48 volumio go-librespot[1729]: Librespot-go daemon starting... Jul 15 07:29:48 volumio go-librespot[1729]: time="2024-07-15T07:29:48+02:00" level=info msg="generated new device id: 81bc5175e4a5b662b2611a3de4cb02301d10ef79" Jul 15 07:29:48 volumio go-librespot[1729]: time="2024-07-15T07:29:48+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:49 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29:49+02:00" level=debug msg="new websocket client" Jul 15 07:29:49 volumio volumio[877]: info: Connection to go-librespot Websocket established Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29:49+02:00" level=debug msg="obtained new client token: AAAirx7tApwRqkAJLFSvdO+N7gqL5vFzOGC0pw8eh1i1p6c1kszM8O+KQLxTT5tKXyjvFM2NIDNnxn51SDi7IC0BL0DUShZXCjQqv7Y2AXgGQM13nLqjp9SiWobFNODPziOOsQgUR+ZEjIwheO+VA0RBLaJa80pBDLcUTQZFP/WYVds6j0V1izOd5bWC5RwfWNkw/NajrdBfyozUDK1xcnvnT9iMU8y7gD6lDBcN7F1/fLNTJQbZjtPV17M=" Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29:49+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:49 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29:49+02:00" level=debug msg="completed challenge" Jul 15 07:29:49 volumio go-librespot[1729]: time="2024-07-15T07:29:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:49 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jul 15 07:29:50 volumio volumio[877]: info: Preload queue cleared Jul 15 07:29:52 volumio volumio[877]: info: Getting Spotify volume Jul 15 07:29:52 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:52 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 15 07:29:52 volumio volumio[877]: (node:877) 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: 7) Jul 15 07:29:52 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 15 07:29:52 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:52 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:52 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jul 15 07:29:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 07:29:52 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:52 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jul 15 07:29:52 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:52 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:52 volumio go-librespot[1737]: Librespot-go daemon starting... Jul 15 07:29:52 volumio go-librespot[1737]: time="2024-07-15T07:29:52+02:00" level=info msg="generated new device id: f3acc3d2f15f00f00d31b3ff1b11c604f077ab83" Jul 15 07:29:52 volumio go-librespot[1737]: time="2024-07-15T07:29:52+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:53 volumio go-librespot[1737]: time="2024-07-15T07:29:53+02:00" level=debug msg="obtained new client token: AAC/WTDyaLAPB5ZENtr9mmCTiue5WyonkWX2C5rbUHNAqCxwNZpGZ8Ijo93h1nKCnVD3vpu7l4v/jHBgWhv8dAzV/NZGF+IsNbghBlUzd00cmJIeBZPW8n9KH/Bb649s8SFES7f4wsHa9Rs/xA29LmnT0m6ZELLTYuMrbi5lBJgPndBFb4TsTi5rRnbefCDigG5bmVj8P8nKwqI4ErFV8I7xIajX+3YCgBtopQMHVPS5XKycHjThFOe5n1A=" Jul 15 07:29:53 volumio go-librespot[1737]: time="2024-07-15T07:29: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 15 07:29:53 volumio go-librespot[1737]: time="2024-07-15T07:29:53+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:53 volumio go-librespot[1737]: time="2024-07-15T07:29:53+02:00" level=debug msg="completed challenge" Jul 15 07:29:53 volumio go-librespot[1737]: time="2024-07-15T07:29:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Jul 15 07:29:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 15 07:29:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 15 07:29:53 volumio volumio[877]: info: Preload queue cleared Jul 15 07:29:55 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jul 15 07:29:55 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 15 07:29:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jul 15 07:29:56 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Jul 15 07:29:56 volumio volumio[877]: info: Listing playlists Jul 15 07:29:56 volumio volumio[877]: info: Listing playlists Jul 15 07:29:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 15 07:29:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jul 15 07:29:56 volumio systemd[1]: Stopped go-librespot Daemon. Jul 15 07:29:56 volumio systemd[1]: Started go-librespot Daemon. Jul 15 07:29:56 volumio go-librespot[1745]: Librespot-go daemon starting... Jul 15 07:29:56 volumio go-librespot[1745]: time="2024-07-15T07:29:56+02:00" level=info msg="generated new device id: 9da990cc29bab6d9bb2291570e608f2855ca0849" Jul 15 07:29:56 volumio go-librespot[1745]: time="2024-07-15T07:29:56+02:00" level=debug msg="stored credentials found for 31qvq64bwblgz5oeju3zpkkvobje" Jul 15 07:29:57 volumio go-librespot[1745]: time="2024-07-15T07:29:57+02:00" level=debug msg="obtained new client token: AACT0Acr5R6WppACPUOxJ+yZztqv/TuyL1ZCkxIFRr/+wnt1r80nBhTo7AMUl6hfnEzlWoc25WwLSLom9AvqrL8dY2Dr6RgP1HbtZTc8WOiwo9ORndEdSosxUnR3d7PtvaCG0DAVH1nx8hzWtfZcfXmJ+nRtg9E4vtPd8cAIjyNhd9pGpCm/YRfWefMjkuYUH0pu4mq94lRIRo7H02uIauAxU2LIAZxxiT56ymOpmzFHmMzc9lhubFM2d44=" Jul 15 07:29:57 volumio go-librespot[1745]: time="2024-07-15T07:29: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 15 07:29:57 volumio go-librespot[1745]: time="2024-07-15T07:29:57+02:00" level=debug msg="completed keyexchange" Jul 15 07:29:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 07:29:57 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 07:29:57 volumio volumio[877]: TypeError: Cannot read property 'length' of undefined Jul 15 07:29:57 volumio volumio[877]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 15 07:29:57 volumio volumio[877]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 15 07:29:57 volumio volumio[877]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 15 07:29:57 volumio volumio[877]: at Parser.emit (events.js:315:20) Jul 15 07:29:57 volumio volumio[877]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 15 07:29:57 volumio volumio[877]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 15 07:29:57 volumio volumio[877]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 15 07:29:57 volumio volumio[877]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 15 07:29:57 volumio volumio[877]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 15 07:29:57 volumio volumio[877]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 15 07:29:57 volumio volumio[877]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 15 07:29:57 volumio volumio[877]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 15 07:29:57 volumio volumio[877]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 15 07:29:57 volumio volumio[877]: at IncomingMessage.emit (events.js:327:22) Jul 15 07:29:57 volumio volumio[877]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 15 07:29:57 volumio volumio[877]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 15 07:29:57 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 07:29:57 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 07:28 Jul 15 07:29:57 volumio sudo[1762]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"