-- Logs begin at Sun 2024-04-14 14:23:07 CST, end at Sun 2024-04-14 14:31:02 CST. -- Apr 14 14:30:00 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:00 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Apr 14 14:30:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:00 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:00 volumio go-librespot[3254]: Librespot-go daemon starting... Apr 14 14:30:00 volumio go-librespot[3254]: time="2024-04-14T14:30:00+08:00" level=info msg="generated new device id: ff8bc4dbd2976bfa29d2b7be158a6761296e5445" Apr 14 14:30:00 volumio go-librespot[3254]: time="2024-04-14T14:30:00+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:01 volumio go-librespot[3254]: time="2024-04-14T14:30:01+08:00" level=debug msg="obtained new client token: AAD9ldcGYw8plW3ex/3bxEYfxA4TCcm5XD222EmrNitKo92nLaAeLCNaAAJNDTBin617wkp/jSCTJjUeJODlu9653H64AuIn2xya4Oy+l0Q9R2Bw6vIxhLbFpV4JSrvXnXMQq1OQM7MN4+bGfStn6qe1bQey9FqVSGJaRi+JX7upr2ix9j5KzxuCE7QmRVsWTNVV9g6n4Lo2xflw+/f9bZ63ghL9iQCHa/o3xvhnXFddBkcZ8ksnoRWp3YKdmayh" Apr 14 14:30:02 volumio go-librespot[3254]: time="2024-04-14T14:30:02+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:02 volumio go-librespot[3254]: time="2024-04-14T14:30:02+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:02 volumio go-librespot[3254]: time="2024-04-14T14:30:02+08:00" level=debug msg="completed challenge" Apr 14 14:30:02 volumio go-librespot[3254]: time="2024-04-14T14:30:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:03 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:03 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Apr 14 14:30:06 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:06 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:06 volumio go-librespot[3275]: Librespot-go daemon starting... Apr 14 14:30:06 volumio go-librespot[3275]: time="2024-04-14T14:30:06+08:00" level=info msg="generated new device id: eea17740189a24cdaca9ec7a039f77cf142a15f3" Apr 14 14:30:06 volumio go-librespot[3275]: time="2024-04-14T14:30:06+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:06 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:06 volumio go-librespot[3275]: time="2024-04-14T14:30:06+08:00" level=debug msg="new websocket client" Apr 14 14:30:06 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:06 volumio go-librespot[3275]: time="2024-04-14T14:30:06+08:00" level=debug msg="obtained new client token: AACdRFQ0ilywsEnE86d3uosyagWm5kNHPry3EfxHHLFC6NqacT/9Z9z0BrYtGQUdbtQJz2qdsme59D9Oh4N/aExpUwxytVUzuKrZH04Gy/qzFyd2RwBq/qHtvMjE+7qn0n1lxYvqyTr1YEUuiyruNRaceKylQjOSz4Y+pY9vCyHbHSAdm4rWdV+h7/RBcnAMzbjzagrmUmhTvkrQ+eylr1N7Zn6TWeZk/fbSIzWGUkMBKR+/6mGCiD2X783rJ8jQ" Apr 14 14:30:07 volumio go-librespot[3275]: time="2024-04-14T14:30:07+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:07 volumio go-librespot[3275]: time="2024-04-14T14:30:07+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:07 volumio go-librespot[3275]: time="2024-04-14T14:30:07+08:00" level=debug msg="completed challenge" Apr 14 14:30:08 volumio go-librespot[3275]: time="2024-04-14T14:30:08+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:08 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:09 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:09 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:09 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:09 volumio volumio[836]: (node:836) 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: 45) Apr 14 14:30:09 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 14 14:30:09 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:09 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:11 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:11 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Apr 14 14:30:11 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:11 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:11 volumio go-librespot[3293]: Librespot-go daemon starting... Apr 14 14:30:11 volumio go-librespot[3293]: time="2024-04-14T14:30:11+08:00" level=info msg="generated new device id: 80a0ae99ae1ce8416a4b2a9c69cc47c55890ca44" Apr 14 14:30:11 volumio go-librespot[3293]: time="2024-04-14T14:30:11+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:11 volumio go-librespot[3293]: time="2024-04-14T14:30:11+08:00" level=debug msg="obtained new client token: AAAHTaTBI+65wJcE0jWBWmGXL/Jn+WNq+NWyn7dXuCgulbilS1ETV8MFKNPiHJw3uDtrvKF8aKpyvSYVm5Iim0bmjrTp4MQxGuljn9pgN51ukY3O5J/RymamAbK8T4B8p0gaHvRSnlEhpKNrjBfwKREwkqfYk3SU5p4CRRc20eNsFI2qSIi5jEF/x0hCQstFuCcAeMcRqXR7vgk18MH8Fijidwczz7SNie3i55974kzMdEyF1kgQNQ524tJkFaZw" Apr 14 14:30:12 volumio go-librespot[3293]: time="2024-04-14T14:30:12+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:12 volumio go-librespot[3293]: time="2024-04-14T14:30:12+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:13 volumio go-librespot[3293]: time="2024-04-14T14:30:13+08:00" level=debug msg="completed challenge" Apr 14 14:30:13 volumio go-librespot[3293]: time="2024-04-14T14:30:13+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:14 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:14 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Apr 14 14:30:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:16 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:16 volumio go-librespot[3312]: Librespot-go daemon starting... Apr 14 14:30:16 volumio go-librespot[3312]: time="2024-04-14T14:30:16+08:00" level=info msg="generated new device id: ac7f76b04360682f694593fd1ba31cb3aff7aa63" Apr 14 14:30:16 volumio go-librespot[3312]: time="2024-04-14T14:30:16+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:17 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:17 volumio go-librespot[3312]: time="2024-04-14T14:30:17+08:00" level=debug msg="new websocket client" Apr 14 14:30:17 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:17 volumio go-librespot[3312]: time="2024-04-14T14:30:17+08:00" level=debug msg="obtained new client token: AACUDb7KjpeSc6ucr1gXQDXcx6lEgYcy0oQ6OYAFuXOR41SM79EukAzGZNZVDHylqpktSD9hC8J1spBMLSLag9/lcy4TdmplqTqHY3DKjPe9teA7RWJbinldokDVqgLqWbfnkN+YCxwrYkW3ONqGG/5YvdSwOcG6yB7Ul7tcgXuG3vOXS5/8xessKRmdHvVwJbDFPwGk99FC6I5AsQie1vhO4lAOPZwlY8FE+sH/I4PMGzAzhYxiyXeQyU6O+9yd" Apr 14 14:30:18 volumio go-librespot[3312]: time="2024-04-14T14:30:18+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 14 14:30:18 volumio go-librespot[3312]: time="2024-04-14T14:30:18+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:18 volumio go-librespot[3312]: time="2024-04-14T14:30:18+08:00" level=debug msg="completed challenge" Apr 14 14:30:18 volumio go-librespot[3312]: time="2024-04-14T14:30:18+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:18 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:20 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:20 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:20 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:20 volumio volumio[836]: (node:836) 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: 46) Apr 14 14:30:20 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 14 14:30:20 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:20 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:21 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:21 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Apr 14 14:30:22 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:22 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:22 volumio go-librespot[3332]: Librespot-go daemon starting... Apr 14 14:30:22 volumio go-librespot[3332]: time="2024-04-14T14:30:22+08:00" level=info msg="generated new device id: ccdffd9d4ce71e028719a2533b28c2f39decd163" Apr 14 14:30:22 volumio go-librespot[3332]: time="2024-04-14T14:30:22+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:22 volumio go-librespot[3332]: time="2024-04-14T14:30:22+08:00" level=debug msg="obtained new client token: AAByDGQEoBAkFhC/JGseVldeGcRxjfXlbrdI3nX6YI4FQNt21HcBjEt4rzDRIpz5CxdSSKI9Ev301v7iOKWnpTSJ84UqqGtfSF74tFAoBFP5XYvOIzEJAJnu6xB+xgTq+VzbIQhBoW9G3iVTTLYizy1vfDHsZ4GOb7Fr8W//LCDNl3qUsa9jz5fv2/VTaM3s4SdxPlSdaVNXZ8UkLPNjOEq/LCvQnGi7sAaBjwXEUcuk76osGPL9FFfIc0CQD+Nd" Apr 14 14:30:23 volumio go-librespot[3332]: time="2024-04-14T14:30:23+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:23 volumio go-librespot[3332]: time="2024-04-14T14:30:23+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:23 volumio go-librespot[3332]: time="2024-04-14T14:30:23+08:00" level=debug msg="completed challenge" Apr 14 14:30:23 volumio go-librespot[3332]: time="2024-04-14T14:30:23+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:24 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:24 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Apr 14 14:30:27 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:27 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:27 volumio go-librespot[3350]: Librespot-go daemon starting... Apr 14 14:30:27 volumio go-librespot[3350]: time="2024-04-14T14:30:27+08:00" level=info msg="generated new device id: 9cc61eb7493d549027d57fd999bcf6b24f0e7158" Apr 14 14:30:27 volumio go-librespot[3350]: time="2024-04-14T14:30:27+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:27 volumio go-librespot[3350]: time="2024-04-14T14:30:27+08:00" level=debug msg="obtained new client token: AAB4Xr2aJaJ5rv6EYEGjx6s5okQAf5ywH8ZPZcr3hxSIH6csO+NGO+iIHpzAUQMFseiA4tcYOhEE/mvdUyQ5JiKf6CRZYBl2NfuZmhL8lucF02/HxkKOamgDEFoJbL86Fcgk4xa66FJ2abUhARHde0X2/ovUG3xNwm2C3xu9qQ0A68WsBrzw9zt/7hyRNRN3hl1fSGwktWnZO69E9ZezSj1jAaIuiK68u7V517uHzfL4Mz9eJzpHANFPBDLf4+xV" Apr 14 14:30:27 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:27 volumio go-librespot[3350]: time="2024-04-14T14:30:27+08:00" level=debug msg="new websocket client" Apr 14 14:30:27 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:28 volumio go-librespot[3350]: time="2024-04-14T14:30:28+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 14 14:30:28 volumio go-librespot[3350]: time="2024-04-14T14:30:28+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:28 volumio go-librespot[3350]: time="2024-04-14T14:30:28+08:00" level=debug msg="completed challenge" Apr 14 14:30:29 volumio go-librespot[3350]: time="2024-04-14T14:30:29+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:29 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:30 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:31 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:31 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:31 volumio volumio[836]: (node:836) 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: 47) Apr 14 14:30:31 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 14 14:30:31 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:31 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:32 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Apr 14 14:30:32 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:32 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:32 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:32 volumio go-librespot[3368]: Librespot-go daemon starting... Apr 14 14:30:32 volumio go-librespot[3368]: time="2024-04-14T14:30:32+08:00" level=info msg="generated new device id: 0b46c20c836a6602ec00aee851bed41c5cdf08c8" Apr 14 14:30:32 volumio go-librespot[3368]: time="2024-04-14T14:30:32+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:33 volumio go-librespot[3368]: time="2024-04-14T14:30:33+08:00" level=debug msg="obtained new client token: AACgnvbNyxF5bljO1kya9ujKGWni7sA9Vti0oPl2rKfmTia/c3aAsnAldsheIHP1T7I357Mf7R6/AW6XG3SLGGjlRXpWEtqWkgXo8ZzQANj+UwqSOFfJas0BeHy/5tuQbsLie8PnO6Be8+llP5JfypRyaYYFpqF7Af5UtwSnLAJOr1EnJqN3Ro2VS4Ev9kUs0uwFbl9BXvDoG/6rPFb9xlIZRJ2QURQ/p9C3h4g3DUbNa8EdK4MvQvME6iY28eq4" Apr 14 14:30:34 volumio go-librespot[3368]: time="2024-04-14T14:30:34+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:34 volumio go-librespot[3368]: time="2024-04-14T14:30:34+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:34 volumio go-librespot[3368]: time="2024-04-14T14:30:34+08:00" level=debug msg="completed challenge" Apr 14 14:30:35 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:35 volumio go-librespot[3368]: time="2024-04-14T14:30:35+08:00" level=debug msg="new websocket client" Apr 14 14:30:35 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:35 volumio go-librespot[3368]: time="2024-04-14T14:30:35+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:35 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:38 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:38 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:38 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:38 volumio volumio[836]: (node:836) 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: 48) Apr 14 14:30:38 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 14 14:30:38 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:38 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:38 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:38 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Apr 14 14:30:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:38 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:38 volumio go-librespot[3432]: Librespot-go daemon starting... Apr 14 14:30:38 volumio go-librespot[3432]: time="2024-04-14T14:30:38+08:00" level=info msg="generated new device id: 3e38bad6b0173ec6c3e84f56172771a6563e4326" Apr 14 14:30:38 volumio go-librespot[3432]: time="2024-04-14T14:30:38+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:39 volumio go-librespot[3432]: time="2024-04-14T14:30:39+08:00" level=debug msg="obtained new client token: AAAQCafpEJBc0khNu3NkUAe4//loNYViftA+iQTZS2Rl/9RHc85GBLQZLBSmPlQFij11KHvImMADQyTt6CoUavfebvAuBGTsJuRMbrSwObFfRy005FIu8RKYYy/JHNXEroFueKc3J6e6pg2MIxJk53zaCsGgXlNK9GJ4gExUzIjGrCsJE2yKvgN8lCwQJ/V2rJJcGQ6DgUoHfeVdtvGZX3GdvP+spB7oJoFOwdm7XLm1BiI2Pud3qqzqBc8soE29" Apr 14 14:30:39 volumio go-librespot[3432]: time="2024-04-14T14:30:39+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 14 14:30:39 volumio go-librespot[3432]: time="2024-04-14T14:30:39+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:40 volumio go-librespot[3432]: time="2024-04-14T14:30:40+08:00" level=debug msg="completed challenge" Apr 14 14:30:40 volumio go-librespot[3432]: time="2024-04-14T14:30:40+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:41 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:41 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Apr 14 14:30:43 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:43 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:43 volumio go-librespot[3450]: Librespot-go daemon starting... Apr 14 14:30:43 volumio go-librespot[3450]: time="2024-04-14T14:30:43+08:00" level=info msg="generated new device id: 713a93f170ab81aa6f391ef8a05f598b6b63b0cd" Apr 14 14:30:43 volumio go-librespot[3450]: time="2024-04-14T14:30:43+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:44 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:44 volumio go-librespot[3450]: time="2024-04-14T14:30:44+08:00" level=debug msg="new websocket client" Apr 14 14:30:44 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:44 volumio go-librespot[3450]: time="2024-04-14T14:30:44+08:00" level=debug msg="obtained new client token: AADwtRsaqZQ+nQlFhFcoZ7ceoATBvnYpmgSSJ+lyN5p3V3HwePvbp9EWRRhdjZO5XQodK+rwZb18ehQL4b8MjTelm3lqgJ1dfiO1nl2O7H2QquxHLgVLhf5IEqiPpqONZ9Pn6d34atDjiAJpTuQwNuqYqQkt9OOjWz46r0MpkK8JtPAw663pOxRw+dzUw6x7ISuhXNa9uBLHLfgg9SrdZ47rW9qC1pWpFvL4XbuuQZzV70uKSwWafDKOB2yWug==" Apr 14 14:30:44 volumio go-librespot[3450]: time="2024-04-14T14:30:44+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:44 volumio go-librespot[3450]: time="2024-04-14T14:30:44+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:45 volumio go-librespot[3450]: time="2024-04-14T14:30:45+08:00" level=debug msg="completed challenge" Apr 14 14:30:45 volumio go-librespot[3450]: time="2024-04-14T14:30:45+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:45 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:47 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:47 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:47 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:47 volumio volumio[836]: (node:836) 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: 49) Apr 14 14:30:47 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 14 14:30:47 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:47 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:48 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:48 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Apr 14 14:30:48 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:48 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:48 volumio go-librespot[3470]: Librespot-go daemon starting... Apr 14 14:30:48 volumio go-librespot[3470]: time="2024-04-14T14:30:48+08:00" level=info msg="generated new device id: 3a330350da28577330ffab85c07124dc2f992b15" Apr 14 14:30:48 volumio go-librespot[3470]: time="2024-04-14T14:30:48+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:49 volumio go-librespot[3470]: time="2024-04-14T14:30:49+08:00" level=debug msg="obtained new client token: AABSjg2VtN2g8c9eDzI1rBhB5rhj1wKc7D06jShqkKI4h/GLTDOdFS+H/HyvBYNkKfldvfUM5QRKpv8Y7fLVH/KVx9FKbkK+MZe+zQc1Vp/aZIbKAaI1dIt4CL0NWUmitej/mI7doLtYZJ6gb+9cyqmxrR7BvoAiD5GlvgyyjAWh6JteCeMv9U3UEUFPVbAbyrY5tj3RaCXv6pzTtsaOT9922v4iZd+Z81/oqNj8KL9EBUzRePF4NM8nSOc2i3IW" Apr 14 14:30:50 volumio go-librespot[3470]: time="2024-04-14T14:30:50+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:50 volumio go-librespot[3470]: time="2024-04-14T14:30:50+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:50 volumio go-librespot[3470]: time="2024-04-14T14:30:50+08:00" level=debug msg="completed challenge" Apr 14 14:30:50 volumio go-librespot[3470]: time="2024-04-14T14:30:50+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:51 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:51 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Apr 14 14:30:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:54 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:54 volumio go-librespot[3488]: Librespot-go daemon starting... Apr 14 14:30:54 volumio go-librespot[3488]: time="2024-04-14T14:30:54+08:00" level=info msg="generated new device id: cdc7463d64e20ae773aa7ae931209135a6817e03" Apr 14 14:30:54 volumio go-librespot[3488]: time="2024-04-14T14:30:54+08:00" level=debug msg="stored credentials not found" Apr 14 14:30:54 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:54 volumio go-librespot[3488]: time="2024-04-14T14:30:54+08:00" level=debug msg="new websocket client" Apr 14 14:30:54 volumio volumio[836]: info: Connection to go-librespot Websocket established Apr 14 14:30:55 volumio go-librespot[3488]: time="2024-04-14T14:30:55+08:00" level=debug msg="obtained new client token: AADU1naumKRHZhGTJfqT2u5aeZkm4F7cihB8Wj20ltz17H2jHm1dsPSlvLO86z0v9Pqy/7FpBP5h7cH+g+ACQLwsLe3rWQxay6St+5zW63NKyUZXJs+qa5B5+McxmEvyaqYL/TxbNcyuSUvF7IcMq23VrS470oZWg7SKJsDmV5SbuzReg0w7CpZFcQj9kS2wDCQQnVEpPkOye16sPY5bR92eRy45OxQe7wJtPssPsw5ZYPeZPAtYcX5t6wx/lhZ+" Apr 14 14:30:55 volumio go-librespot[3488]: time="2024-04-14T14:30:55+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:30:55 volumio go-librespot[3488]: time="2024-04-14T14:30:55+08:00" level=debug msg="completed keyexchange" Apr 14 14:30:56 volumio go-librespot[3488]: time="2024-04-14T14:30:56+08:00" level=debug msg="completed challenge" Apr 14 14:30:56 volumio go-librespot[3488]: time="2024-04-14T14:30:56+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:30:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:30:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:30:56 volumio volumio[836]: info: Connection to go-librespot Websocket closed Apr 14 14:30:57 volumio volumio[836]: info: Getting Spotify volume Apr 14 14:30:57 volumio volumio[836]: (node:836) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:57 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 14 14:30:57 volumio volumio[836]: (node:836) 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: 50) Apr 14 14:30:57 volumio volumio[836]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 14 14:30:57 volumio volumio[836]: info: CoreCommandRouter::volumioGetState Apr 14 14:30:57 volumio volumio[836]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 14 14:30:59 volumio volumio[836]: info: Initializing connection to go-librespot Websocket Apr 14 14:30:59 volumio volumio[836]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 14:30:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 14 14:30:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Apr 14 14:30:59 volumio systemd[1]: Stopped go-librespot Daemon. Apr 14 14:30:59 volumio systemd[1]: Started go-librespot Daemon. Apr 14 14:30:59 volumio go-librespot[3510]: Librespot-go daemon starting... Apr 14 14:30:59 volumio go-librespot[3510]: time="2024-04-14T14:30:59+08:00" level=info msg="generated new device id: ff3f0be58947b43dd3875ba6009dc273aac90bb7" Apr 14 14:30:59 volumio go-librespot[3510]: time="2024-04-14T14:30:59+08:00" level=debug msg="stored credentials not found" Apr 14 14:31:00 volumio go-librespot[3510]: time="2024-04-14T14:31:00+08:00" level=debug msg="obtained new client token: AAB+gNGG0y1VMkR4TuXlax+PnICGzNyUG1sLFEdX9f9GfRdLc+sfRHUTgx7Ni+BngO0NawzoGZfq4fZyUx0HYBNpUZt3mqCmMfpo3lklBf9AZyucJs69eL/OmX9nl43/rbikzP/nrkecsYgw3JMCNGFk+joa7BQXxy1wEtaASZ/D2afUFmBfwIhDjWq+AO2j32bVcut91hKuyfM2VghyDct9EBq5GiyOpIFBF9Lx13N6x9fgP+HXlQMJ3iCWbQ==" Apr 14 14:31:00 volumio go-librespot[3510]: time="2024-04-14T14:31:00+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 14:31:00 volumio go-librespot[3510]: time="2024-04-14T14:31:00+08:00" level=debug msg="completed keyexchange" Apr 14 14:31:01 volumio go-librespot[3510]: time="2024-04-14T14:31:01+08:00" level=debug msg="completed challenge" Apr 14 14:31:01 volumio volumio[836]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:31:01 volumio volumio[836]: Error: connect ETIMEDOUT 173.252.248.244:80 Apr 14 14:31:01 volumio volumio[836]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 14 14:31:01 volumio volumio[836]: errno: -110, Apr 14 14:31:01 volumio volumio[836]: code: 'ETIMEDOUT', Apr 14 14:31:01 volumio volumio[836]: syscall: 'connect', Apr 14 14:31:01 volumio volumio[836]: address: '173.252.248.244', Apr 14 14:31:01 volumio volumio[836]: port: 80 Apr 14 14:31:01 volumio volumio[836]: } Apr 14 14:31:01 volumio volumio[836]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:31:01 volumio go-librespot[3510]: time="2024-04-14T14:31:01+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Apr 14 14:31:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:31:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 14:31:02 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 14:30 Apr 14 14:31:02 volumio sudo[3537]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"