-- Logs begin at Fri 2024-04-26 19:07:29 EEST, end at Fri 2024-04-26 22:05:41 EEST. -- Apr 26 22:04:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 26 22:04:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 499. Apr 26 22:04:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 26 22:04:02 volumio systemd[1]: Started go-librespot Daemon. Apr 26 22:04:02 volumio go-librespot[19330]: Librespot-go daemon starting... Apr 26 22:04:02 volumio go-librespot[19330]: time="2024-04-26T22:04:02+03:00" level=info msg="generated new device id: 098cc3e314303f05f8ac9974c54669cf3ddbf1d3" Apr 26 22:04:04 volumio ntpd[855]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 26 22:04:20 volumio go-librespot[19330]: time="2024-04-26T22:04:20+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Apr 26 22:04:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 26 22:04:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 26 22:04:22 volumio ntpd[855]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 26 22:04:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 26 22:04:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 500. Apr 26 22:04:24 volumio systemd[1]: Stopped go-librespot Daemon. Apr 26 22:04:24 volumio systemd[1]: Started go-librespot Daemon. Apr 26 22:04:24 volumio go-librespot[19340]: Librespot-go daemon starting... Apr 26 22:04:24 volumio go-librespot[19340]: time="2024-04-26T22:04:24+03:00" level=info msg="generated new device id: bfd2bd2557b5d669756dd94d626e1c5dac962379" Apr 26 22:04:34 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 26 22:04:40 volumio go-librespot[19340]: time="2024-04-26T22:04:40+03:00" level=debug msg="new websocket client" Apr 26 22:04:40 volumio volumio[859]: info: Connection to go-librespot Websocket established Apr 26 22:04:40 volumio ntpd[855]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 26 22:04:42 volumio go-librespot[19340]: time="2024-04-26T22:04:42+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Apr 26 22:04:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 26 22:04:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 26 22:04:42 volumio volumio[859]: info: Connection to go-librespot Websocket closed Apr 26 22:04:43 volumio volumio[859]: info: Getting Spotify volume Apr 26 22:04:43 volumio volumio[859]: (node:859) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 26 22:04:43 volumio volumio[859]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 26 22:04:43 volumio volumio[859]: (node:859) 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: 13) Apr 26 22:04:45 volumio volumio[859]: info: Initializing connection to go-librespot Websocket Apr 26 22:04:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 26 22:04:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 501. Apr 26 22:04:45 volumio systemd[1]: Stopped go-librespot Daemon. Apr 26 22:04:45 volumio systemd[1]: Started go-librespot Daemon. Apr 26 22:04:45 volumio go-librespot[19396]: Librespot-go daemon starting... Apr 26 22:04:45 volumio go-librespot[19396]: time="2024-04-26T22:04:45+03:00" level=info msg="generated new device id: 230cc7326139b0791bde227f43b2b994bad3d10e" Apr 26 22:04:58 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 26 22:04:58 volumio ntpd[855]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 26 22:05:03 volumio go-librespot[19396]: time="2024-04-26T22:05:03+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Apr 26 22:05:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 26 22:05:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 26 22:05:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 26 22:05:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 502. Apr 26 22:05:06 volumio systemd[1]: Stopped go-librespot Daemon. Apr 26 22:05:06 volumio systemd[1]: Started go-librespot Daemon. Apr 26 22:05:06 volumio go-librespot[19415]: Librespot-go daemon starting... Apr 26 22:05:06 volumio go-librespot[19415]: time="2024-04-26T22:05:06+03:00" level=info msg="generated new device id: 81a23d53ecca50279e063846b8f4cdbc81cda772" Apr 26 22:05:16 volumio ntpd[855]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 26 22:05:24 volumio go-librespot[19415]: time="2024-04-26T22:05:24+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Apr 26 22:05:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 26 22:05:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 26 22:05:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 26 22:05:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 503. Apr 26 22:05:27 volumio systemd[1]: Stopped go-librespot Daemon. Apr 26 22:05:27 volumio systemd[1]: Started go-librespot Daemon. Apr 26 22:05:27 volumio go-librespot[19423]: Librespot-go daemon starting... Apr 26 22:05:27 volumio go-librespot[19423]: time="2024-04-26T22:05:27+03:00" level=info msg="generated new device id: 80307fbcc6d9a94ad497f7e3f22851476a9d00d9" Apr 26 22:05:28 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Apr 26 22:05:31 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 18 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 19 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 20 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 21 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 22 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 23 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 24 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 25 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 26 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 27 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 28 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 29 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 30 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 31 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 32 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 33 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 34 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 35 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 36 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 37 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 38 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 39 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 40 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 41 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 42 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 43 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 44 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 45 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 46 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 47 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 48 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 49 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 50 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 51 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 52 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 53 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 54 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 55 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 56 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 57 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 58 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 59 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 60 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 61 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 62 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 63 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 64 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 65 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 66 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 67 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 68 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 69 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 70 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 71 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 72 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 73 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 74 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 75 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 76 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 77 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 78 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 79 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 80 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 81 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 82 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 83 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 84 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 85 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 86 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 87 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 88 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 89 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 90 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 91 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 92 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 93 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 94 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 95 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 96 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 97 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 98 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 99 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 100 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 101 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 102 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 103 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 104 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 105 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 106 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 107 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 108 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 109 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 110 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 111 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 112 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 113 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 114 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 115 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 116 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 117 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 118 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 119 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 120 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 121 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 122 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 123 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 124 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 125 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 126 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 127 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 128 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 129 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 130 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 131 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 132 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 133 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 134 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 135 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 136 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 137 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 138 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 139 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 140 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 141 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 142 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 143 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 144 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 145 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 146 Apr 26 22:05:32 volumio go-librespot[19423]: time="2024-04-26T22:05:32+03:00" level=debug msg="new websocket client" Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 147 Apr 26 22:05:32 volumio volumio[859]: info: Connection to go-librespot Websocket established Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 148 Apr 26 22:05:32 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 149 Apr 26 22:05:32 volumio volumio[859]: info: CoreCommandRouter::volumioGetState Apr 26 22:05:32 volumio volumio[859]: info: CorePlayQueue::getTrack 6 Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 22:05:33 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:33 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:33 volumio volumio[859]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 22:05:33 volumio volumio[859]: info: MyVolumio login type: Token Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 22:05:33 volumio volumio[859]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 22:05:34 volumio volumio[859]: info: Starting Streaming Service Transparent Proxy Apr 26 22:05:34 volumio volumio[859]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 22:05:34 volumio volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 22:05:34 volumio volumio[859]: info: Streaming services startup Apr 26 22:05:34 volumio volumio[859]: info: Starting Streaming Daemon Apr 26 22:05:34 volumio sudo[19439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 22:05:34 volumio sudo[19439]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:05:34 volumio sudo[19439]: pam_unix(sudo:session): session closed for user root Apr 26 22:05:34 volumio volumio[859]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 26 22:05:34 volumio volumio[859]: error: Cannot start Volumio Streaming Daemon Apr 26 22:05:34 volumio volumio[859]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 22:05:34 volumio volumio[859]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 22:05:34 volumio volumio[859]: STREAMING PROXY: Starting server on port 3245 Apr 26 22:05:34 volumio volumio[859]: Node JS runtime: 14 Apr 26 22:05:34 volumio volumio[859]: error: MyVolumio Custom Token format not valid, refreshing it Apr 26 22:05:34 volumio ntpd[855]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 26 22:05:34 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:34 volumio ntpd[855]: Soliciting pool server 193.106.144.7 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 79.142.192.130 Apr 26 22:05:35 volumio volumio[859]: info: MyVolumio login type: Token Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 91.236.251.35 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 94.158.46.150 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 91.236.251.29 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 80.254.4.222 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 91.236.251.38 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 162.159.200.1 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 212.1.104.9 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 91.236.251.5 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 213.231.5.55 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 162.159.200.123 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:440:401:91:236:251:13 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:470:70:222::2 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 94.158.46.150 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2a03:6300:1:100:2::123 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 162.159.200.1 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:25dc::29 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:440:401:91:236:251:13 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:470:70:222::2 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 94.158.46.150 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 162.159.200.1 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2a03:6300:1:100:2::123 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:25dc::29 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:440:401:91:236:251:13 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:470:70:222::2 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2a03:6300:1:100:2::123 Apr 26 22:05:35 volumio volumio[859]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 94.158.46.150 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2a03:6300:1:100:2::123 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 162.159.200.1 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 2001:67c:25dc::29 Apr 26 22:05:35 volumio ntpd[855]: Soliciting pool server 130.255.135.221 Apr 26 22:05:35 volumio volumio[859]: info: Getting Spotify volume Apr 26 22:05:35 volumio volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 150 Apr 26 22:05:35 volumio volumio[859]: info: CoreCommandRouter::volumioGetState Apr 26 22:05:35 volumio volumio[859]: info: CorePlayQueue::getTrack 6 Apr 26 22:05:36 volumio volumio[859]: info: MyVolumio token set successfully Apr 26 22:05:36 volumio volumio[859]: info: MYVOLUMIO: Adding device Apr 26 22:05:36 volumio volumio[859]: info: MYVOLUMIO: Evaluating Server Apr 26 22:05:36 volumio volumio[859]: info: MyVolumio status changed Apr 26 22:05:36 volumio volumio[859]: info: Streaming services startup Apr 26 22:05:36 volumio volumio[859]: info: Starting Streaming Daemon Apr 26 22:05:36 volumio volumio[859]: info: Removing browser output: myVolumio user plan is not superstar Apr 26 22:05:36 volumio volumio[859]: info: Removing audio output: Apr 26 22:05:36 volumio volumio[859]: info: Stoppping Tunnel 1 Apr 26 22:05:36 volumio sudo[19464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 22:05:36 volumio sudo[19464]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:05:36 volumio sudo[19464]: pam_unix(sudo:session): session closed for user root Apr 26 22:05:36 volumio volumio[859]: error: Cannot start Volumio Streaming Daemon Apr 26 22:05:36 volumio volumio[859]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 22:05:36 volumio volumio[859]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 22:05:36 volumio sudo[19467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 26 22:05:36 volumio sudo[19467]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:05:36 volumio sudo[19467]: pam_unix(sudo:session): session closed for user root Apr 26 22:05:36 volumio volumio[859]: info: Remote SSH Stopped Apr 26 22:05:36 volumio ntpd[855]: Soliciting pool server 94.158.46.150 Apr 26 22:05:36 volumio volumio[859]: info: Setting Geolocation for MyVolumio to eu9 Apr 26 22:05:36 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:36 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:36 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:37 volumio go-librespot[19423]: time="2024-04-26T22:05:37+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 26 22:05:37 volumio go-librespot[19423]: time="2024-04-26T22:05:37+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 26 22:05:37 volumio go-librespot[19423]: time="2024-04-26T22:05:37+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 26 22:05:37 volumio go-librespot[19423]: time="2024-04-26T22:05:37+03:00" level=debug msg="zeroconf server listening on port 34349" Apr 26 22:05:37 volumio volumio[859]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 26 22:05:37 volumio volumio[859]: info: Updating MyVolumio device info Apr 26 22:05:37 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:37 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:37 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:05:39 volumio volumio[859]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 26 22:05:41 volumio volumio[859]: info: MYVOLUMIO: Adding device Apr 26 22:05:41 volumio volumio[859]: info: MYVOLUMIO: Evaluating Server Apr 26 22:05:41 volumio ntpd[855]: receive: Unexpected origin timestamp 0xe9d67985.c65b817b does not match aorg 0000000000.00000000 from server@193.106.144.7 xmt 0xe9d67985.77605d6b Apr 26 22:05:41 volumio ntpd[855]: receive: Unexpected origin timestamp 0xe9d67985.c65a4d91 does not match aorg 0000000000.00000000 from server@91.236.251.5 xmt 0xe9d67985.775a5da1 Apr 26 22:05:41 volumio ntpd[855]: receive: Unexpected origin timestamp 0xe9d67985.c65b2200 does not match aorg 0000000000.00000000 from server@79.142.192.130 xmt 0xe9d67985.7720746f Apr 26 22:05:41 volumio ntpd[855]: receive: Unexpected origin timestamp 0xe9d67985.c659cd49 does not match aorg 0000000000.00000000 from server@213.231.5.55 xmt 0xe9d67985.778ef304 Apr 26 22:05:41 volumio ntpd[855]: receive: Unexpected origin timestamp 0xe9d67985.c6587d7b does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xe9d67985.79522cc9 Apr 26 22:05:41 volumio volumio[859]: error: Failed to ping endpoint eu5.myvolumio.org : unknown error Apr 26 22:05:41 volumio volumio[859]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:05:41 volumio volumio[859]: Error: Unable to resolve or reject the same promise twice Apr 26 22:05:41 volumio volumio[859]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 26 22:05:41 volumio volumio[859]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Apr 26 22:05:41 volumio volumio[859]: at Socket.emit (events.js:400:28) Apr 26 22:05:41 volumio volumio[859]: at addChunk (internal/streams/readable.js:293:12) Apr 26 22:05:41 volumio volumio[859]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 26 22:05:41 volumio volumio[859]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 26 22:05:41 volumio volumio[859]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 26 22:05:41 volumio volumio[859]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:05:41 volumio sudo[19545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 22:04 Apr 26 22:05:41 volumio sudo[19545]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"