-- Logs begin at Fri 2024-08-30 20:00:07 +07, end at Fri 2024-08-30 20:18:19 +07. -- Aug 30 20:17:01 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:01 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 303. Aug 30 20:17:01 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:01 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:01 volumio go-librespot[4684]: Librespot-go daemon starting... Aug 30 20:17:01 volumio go-librespot[4684]: time="2024-08-30T20:17:01+07:00" level=info msg="generated new device id: c68dbbd1aae2ca495f477a69f09c93b5ebcff7e1" Aug 30 20:17:01 volumio go-librespot[4684]: time="2024-08-30T20:17:01+07: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" Aug 30 20:17:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:04 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:04 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 304. Aug 30 20:17:04 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:04 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:04 volumio go-librespot[4691]: Librespot-go daemon starting... Aug 30 20:17:04 volumio go-librespot[4691]: time="2024-08-30T20:17:04+07:00" level=info msg="generated new device id: 0c7e6d35a477e907375096a1dbc0b34f53340b8f" Aug 30 20:17:04 volumio go-librespot[4691]: time="2024-08-30T20:17:04+07: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" Aug 30 20:17:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:07 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:07 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 305. Aug 30 20:17:08 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:08 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:08 volumio go-librespot[4698]: Librespot-go daemon starting... Aug 30 20:17:08 volumio go-librespot[4698]: time="2024-08-30T20:17:08+07:00" level=info msg="generated new device id: f162da844c7697e32a1518ee9240908b74f5b985" Aug 30 20:17:08 volumio go-librespot[4698]: time="2024-08-30T20:17:08+07: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" Aug 30 20:17:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:10 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:10 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 306. Aug 30 20:17:11 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:11 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:11 volumio go-librespot[4705]: Librespot-go daemon starting... Aug 30 20:17:11 volumio go-librespot[4705]: time="2024-08-30T20:17:11+07:00" level=info msg="generated new device id: 4e72c96734718e4ad910dc819d702ec04113bc46" Aug 30 20:17:11 volumio go-librespot[4705]: time="2024-08-30T20:17:11+07: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" Aug 30 20:17:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:13 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:13 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 307. Aug 30 20:17:14 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:14 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:14 volumio go-librespot[4762]: Librespot-go daemon starting... Aug 30 20:17:14 volumio go-librespot[4762]: time="2024-08-30T20:17:14+07:00" level=info msg="generated new device id: 1eb53209619d3ab3e5b64bab9572ecbc1c4ceba2" Aug 30 20:17:14 volumio go-librespot[4762]: time="2024-08-30T20:17:14+07: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" Aug 30 20:17:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:16 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:16 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 308. Aug 30 20:17:17 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:17 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:17 volumio go-librespot[4769]: Librespot-go daemon starting... Aug 30 20:17:17 volumio go-librespot[4769]: time="2024-08-30T20:17:17+07:00" level=info msg="generated new device id: b95ec992e417b83aa8ef4d16e8dfcd72074e76a9" Aug 30 20:17:17 volumio go-librespot[4769]: time="2024-08-30T20:17:17+07: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" Aug 30 20:17:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:19 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:19 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 309. Aug 30 20:17:21 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:21 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:21 volumio go-librespot[4776]: Librespot-go daemon starting... Aug 30 20:17:21 volumio go-librespot[4776]: time="2024-08-30T20:17:21+07:00" level=info msg="generated new device id: 8f054550abf99a678fbba27810eb0f5bee8822b4" Aug 30 20:17:21 volumio go-librespot[4776]: time="2024-08-30T20:17:21+07: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" Aug 30 20:17:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:22 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:22 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 310. Aug 30 20:17:24 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:24 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:24 volumio go-librespot[4783]: Librespot-go daemon starting... Aug 30 20:17:24 volumio go-librespot[4783]: time="2024-08-30T20:17:24+07:00" level=info msg="generated new device id: ce2dff89fac0140fd6777345cbb7c1be55e0e315" Aug 30 20:17:24 volumio go-librespot[4783]: time="2024-08-30T20:17:24+07: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" Aug 30 20:17:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:25 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:25 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 311. Aug 30 20:17:27 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:27 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:27 volumio go-librespot[4791]: Librespot-go daemon starting... Aug 30 20:17:27 volumio go-librespot[4791]: time="2024-08-30T20:17:27+07:00" level=info msg="generated new device id: 7615de5075ef0b43bbd87db07b30355ef31cbfd0" Aug 30 20:17:27 volumio go-librespot[4791]: time="2024-08-30T20:17:27+07: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" Aug 30 20:17:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:28 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:28 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 312. Aug 30 20:17:30 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:30 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:30 volumio go-librespot[4798]: Librespot-go daemon starting... Aug 30 20:17:30 volumio go-librespot[4798]: time="2024-08-30T20:17:30+07:00" level=info msg="generated new device id: 573de4006e09822219f870946bc706c24bcac61a" Aug 30 20:17:30 volumio go-librespot[4798]: time="2024-08-30T20:17:30+07: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" Aug 30 20:17:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:31 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:31 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 313. Aug 30 20:17:34 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:34 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:34 volumio go-librespot[4805]: Librespot-go daemon starting... Aug 30 20:17:34 volumio go-librespot[4805]: time="2024-08-30T20:17:34+07:00" level=info msg="generated new device id: 950bcbdc22cbd7d8dbdc40162d49570211f3550e" Aug 30 20:17:34 volumio go-librespot[4805]: time="2024-08-30T20:17:34+07: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" Aug 30 20:17:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:34 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:34 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 314. Aug 30 20:17:37 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:37 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:37 volumio go-librespot[4812]: Librespot-go daemon starting... Aug 30 20:17:37 volumio go-librespot[4812]: time="2024-08-30T20:17:37+07:00" level=info msg="generated new device id: 6582498f50b2093a9f4927324de5a65358122ee1" Aug 30 20:17:37 volumio go-librespot[4812]: time="2024-08-30T20:17:37+07: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" Aug 30 20:17:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:37 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:37 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:40 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:40 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 315. Aug 30 20:17:40 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:40 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:40 volumio go-librespot[4819]: Librespot-go daemon starting... Aug 30 20:17:40 volumio go-librespot[4819]: time="2024-08-30T20:17:40+07:00" level=info msg="generated new device id: 9aaaf767cf702e888675fe848951e32a668eccc7" Aug 30 20:17:40 volumio go-librespot[4819]: time="2024-08-30T20:17:40+07: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" Aug 30 20:17:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:43 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:43 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 316. Aug 30 20:17:43 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:43 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:43 volumio go-librespot[4826]: Librespot-go daemon starting... Aug 30 20:17:43 volumio go-librespot[4826]: time="2024-08-30T20:17:43+07:00" level=info msg="generated new device id: d2255d9293046e4c96954e02dc19c3159cc7359c" Aug 30 20:17:43 volumio go-librespot[4826]: time="2024-08-30T20:17:43+07: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" Aug 30 20:17:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:44 volumio ntpd[924]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 30 20:17:46 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:46 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 317. Aug 30 20:17:47 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:47 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:47 volumio go-librespot[4833]: Librespot-go daemon starting... Aug 30 20:17:47 volumio go-librespot[4833]: time="2024-08-30T20:17:47+07:00" level=info msg="generated new device id: c222c7b6a13de2ecc20d00a2b3b94694a8a172f6" Aug 30 20:17:47 volumio go-librespot[4833]: time="2024-08-30T20:17:47+07: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" Aug 30 20:17:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:49 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:49 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 318. Aug 30 20:17:50 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:50 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:50 volumio go-librespot[4841]: Librespot-go daemon starting... Aug 30 20:17:50 volumio go-librespot[4841]: time="2024-08-30T20:17:50+07:00" level=info msg="generated new device id: 0903878b140976546f9ab65def47df343388e770" Aug 30 20:17:50 volumio go-librespot[4841]: time="2024-08-30T20:17:50+07: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" Aug 30 20:17:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:50 volumio ntpd[924]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 30 20:17:51 volumio ntpd[924]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 30 20:17:51 volumio ntpd[924]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 30 20:17:52 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:52 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 319. Aug 30 20:17:53 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:53 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:53 volumio go-librespot[4849]: Librespot-go daemon starting... Aug 30 20:17:53 volumio go-librespot[4849]: time="2024-08-30T20:17:53+07:00" level=info msg="generated new device id: 159bb40f800b33e6e2b09f865da8f0fd2b5f7b58" Aug 30 20:17:53 volumio go-librespot[4849]: time="2024-08-30T20:17:53+07: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" Aug 30 20:17:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:55 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:55 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:17:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:17:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 320. Aug 30 20:17:56 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:17:56 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:17:56 volumio go-librespot[4856]: Librespot-go daemon starting... Aug 30 20:17:56 volumio go-librespot[4856]: time="2024-08-30T20:17:56+07:00" level=info msg="generated new device id: 32fbc16701ba30bfd24e9a1a39783888c7046241" Aug 30 20:17:56 volumio go-librespot[4856]: time="2024-08-30T20:17:56+07: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" Aug 30 20:17:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:17:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:17:58 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:17:58 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:18:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:18:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 321. Aug 30 20:18:00 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:18:00 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:18:00 volumio go-librespot[4863]: Librespot-go daemon starting... Aug 30 20:18:00 volumio go-librespot[4863]: time="2024-08-30T20:18:00+07:00" level=info msg="generated new device id: e74a118f188e6a331f4239943c8db393c4338019" Aug 30 20:18:00 volumio go-librespot[4863]: time="2024-08-30T20:18:00+07: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" Aug 30 20:18:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:18:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:18:01 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:18:01 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 322. Aug 30 20:18:03 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:18:03 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:18:03 volumio go-librespot[4870]: Librespot-go daemon starting... Aug 30 20:18:03 volumio go-librespot[4870]: time="2024-08-30T20:18:03+07:00" level=info msg="generated new device id: 8ac9381c675bbf835cd43ca94e4cb491e592c9dc" Aug 30 20:18:03 volumio go-librespot[4870]: time="2024-08-30T20:18:03+07: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" Aug 30 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:18:03 volumio kernel: tg3 0000:03:00.0 eth0: Link is up at 1000 Mbps, full duplex Aug 30 20:18:03 volumio kernel: tg3 0000:03:00.0 eth0: Flow control is on for TX and on for RX Aug 30 20:18:03 volumio kernel: tg3 0000:03:00.0 eth0: EEE is disabled Aug 30 20:18:03 volumio dhcpcd[813]: eth0: carrier acquired Aug 30 20:18:03 volumio dhcpcd[813]: DUID 00:04:21:31:b6:6a:0d:09:52:5b:bc:02:7e:c5:42:66:59:d2 Aug 30 20:18:03 volumio dhcpcd[813]: eth0: IAID 86:31:db:b7 Aug 30 20:18:03 volumio dhcpcd[813]: eth0: adding address fe80::59f4:99c0:792e:32ee Aug 30 20:18:03 volumio dhcpcd[813]: ipv6_addaddr1: Permission denied Aug 30 20:18:03 volumio dhcpcd[813]: eth0: soliciting an IPv6 router Aug 30 20:18:04 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:18:04 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:18:04 volumio ifplugd(eth0)[919]: Link beat detected. Aug 30 20:18:04 volumio dhcpcd[813]: eth0: soliciting a DHCP lease Aug 30 20:18:04 volumio ifplugd(eth0)[919]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 30 20:18:04 volumio ifplugd(eth0)[919]: client: command failed: No such device (-19) Aug 30 20:18:04 volumio dhcpcd[4900]: sending commands to master dhcpcd process Aug 30 20:18:04 volumio dhcpcd[813]: control command: /sbin/dhcpcd eth0 Aug 30 20:18:04 volumio ifplugd(eth0)[919]: client: sending commands to master dhcpcd process Aug 30 20:18:04 volumio ifplugd(eth0)[919]: Program executed successfully. Aug 30 20:18:06 volumio dhcpcd[813]: eth0: offered 192.168.1.58 from 192.168.1.1 Aug 30 20:18:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:18:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 323. Aug 30 20:18:06 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:18:06 volumio dhcpcd[813]: eth0: probing address 192.168.1.58/24 Aug 30 20:18:06 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:18:06 volumio go-librespot[4926]: Librespot-go daemon starting... Aug 30 20:18:06 volumio go-librespot[4926]: time="2024-08-30T20:18:06+07:00" level=info msg="generated new device id: 6d6d0f5f0693528400d3cf9fe320d020959bad34" Aug 30 20:18:06 volumio go-librespot[4926]: time="2024-08-30T20:18:06+07: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" Aug 30 20:18:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:18:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:18:07 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:18:07 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:18:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:18:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 324. Aug 30 20:18:09 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:18:09 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:18:09 volumio go-librespot[4933]: Librespot-go daemon starting... Aug 30 20:18:09 volumio go-librespot[4933]: time="2024-08-30T20:18:09+07:00" level=info msg="generated new device id: 86cdfed9de7cd4211d3c9770799de7bd477c01e6" Aug 30 20:18:09 volumio go-librespot[4933]: time="2024-08-30T20:18:09+07: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" Aug 30 20:18:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 20:18:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 20:18:10 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:18:10 volumio volumio[966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 20:18:10 volumio dhcpcd[813]: eth0: leased 192.168.1.58 for 10800 seconds Aug 30 20:18:10 volumio dhcpcd[813]: eth0: adding route to 192.168.1.0/24 Aug 30 20:18:10 volumio dhcpcd[813]: eth0: adding default route via 192.168.1.1 Aug 30 20:18:10 volumio avahi-daemon[748]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.58. Aug 30 20:18:10 volumio avahi-daemon[748]: New relevant interface eth0.IPv4 for mDNS. Aug 30 20:18:10 volumio avahi-daemon[748]: Registering new address record for 192.168.1.58 on eth0.IPv4. Aug 30 20:18:10 volumio ntpd[924]: ntpd exiting on signal 15 (Terminated) Aug 30 20:18:10 volumio systemd[1]: Stopping Network Time Service... Aug 30 20:18:10 volumio systemd[1]: ntp.service: Succeeded. Aug 30 20:18:10 volumio systemd[1]: Stopped Network Time Service. Aug 30 20:18:10 volumio systemd[1]: Starting Network Time Service... Aug 30 20:18:10 volumio ntpd[4973]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 30 20:18:10 volumio ntpd[4973]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 30 20:18:10 volumio systemd[1]: Started Network Time Service. Aug 30 20:18:10 volumio ntpd[4980]: proto: precision = 0.085 usec (-23) Aug 30 20:18:10 volumio ntpd[4980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 30 20:18:10 volumio ntpd[4980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 30 20:18:10 volumio ntpd[4980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 612 days ago Aug 30 20:18:10 volumio ntpd[4980]: Listen and drop on 0 v6wildcard [::]:123 Aug 30 20:18:10 volumio ntpd[4980]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 30 20:18:10 volumio ntpd[4980]: Listen normally on 2 lo 127.0.0.1:123 Aug 30 20:18:10 volumio ntpd[4980]: Listen normally on 3 eth0 192.168.1.58:123 Aug 30 20:18:10 volumio ntpd[4980]: Listening on routing socket on fd #20 for interface updates Aug 30 20:18:10 volumio ntpd[4980]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 30 20:18:10 volumio ntpd[4980]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 30 20:18:11 volumio volumio[966]: info: Discovery: adding a68c15c1-95fe-40d3-a748-e04b717a1cfa Aug 30 20:18:11 volumio volumio[966]: info: Discovery: Found device Volumio Aug 30 20:18:11 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:18:11 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:18:11 volumio ntpd[4980]: Soliciting pool server 203.113.174.44 Aug 30 20:18:12 volumio ntpd[4980]: Soliciting pool server 103.184.124.23 Aug 30 20:18:13 volumio ntpd[4980]: Soliciting pool server 27.71.27.209 Aug 30 20:18:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 20:18:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 325. Aug 30 20:18:13 volumio systemd[1]: Stopped go-librespot Daemon. Aug 30 20:18:13 volumio systemd[1]: Started go-librespot Daemon. Aug 30 20:18:13 volumio go-librespot[4983]: Librespot-go daemon starting... Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07:00" level=info msg="generated new device id: 839e5b8ae2c68db3502ef643e9cfba4e3366d609" Aug 30 20:18:13 volumio volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07:00" level=debug msg="new websocket client" Aug 30 20:18:13 volumio volumio[966]: info: Connection to go-librespot Websocket established Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07: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]" Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 20:18:13 volumio go-librespot[4983]: time="2024-08-30T20:18:13+07:00" level=debug msg="zeroconf server listening on port 41863" Aug 30 20:18:13 volumio ntpd[4980]: Soliciting pool server 115.165.161.155 Aug 30 20:18:13 volumio ntpd[4980]: Soliciting pool server 115.165.161.155 Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 20:18:14 volumio volumio[966]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 20:18:15 volumio ntpd[4980]: Soliciting pool server 103.184.124.23 Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 20:18:15 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 20:18:15 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 20:18:15 volumio volumio[966]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 20:18:15 volumio volumio[966]: info: MyVolumio login type: Token Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 20:18:15 volumio volumio[966]: info: Starting Streaming Service Transparent Proxy Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 20:18:15 volumio volumio[966]: info: Streaming services startup Aug 30 20:18:15 volumio volumio[966]: info: Starting Streaming Daemon Aug 30 20:18:15 volumio sudo[5052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 20:18:15 volumio sudo[5052]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:18:15 volumio sudo[5052]: pam_unix(sudo:session): session closed for user root Aug 30 20:18:15 volumio volumio[966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 20:18:15 volumio volumio[966]: error: Cannot start Volumio Streaming Daemon Aug 30 20:18:15 volumio volumio[966]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 20:18:15 volumio volumio[966]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 20:18:15 volumio volumio[966]: STREAMING PROXY: Starting server on port 3245 Aug 30 20:18:15 volumio volumio[966]: Node JS runtime: 14 Aug 30 20:18:16 volumio volumio[966]: error: MyVolumio Custom Token format not valid, refreshing it Aug 30 20:18:16 volumio volumio[966]: info: Getting Spotify volume Aug 30 20:18:16 volumio volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 30 20:18:16 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:18:16 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:18:17 volumio volumio[966]: info: MyVolumio login type: Token Aug 30 20:18:18 volumio volumio[966]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 20:18:19 volumio volumio[966]: info: Discovery: Getting this device information Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:18:19 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 20:18:19 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.1.58:3000 from 192.168.1.232 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:18:19 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 20:18:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 20:18:19 volumio volumio[966]: info: MyVolumio token set successfully Aug 30 20:18:19 volumio volumio[966]: info: MYVOLUMIO: Adding device Aug 30 20:18:19 volumio volumio[966]: info: MYVOLUMIO: Evaluating Server Aug 30 20:18:19 volumio volumio[966]: info: MyVolumio status changed Aug 30 20:18:19 volumio volumio[966]: info: Streaming services startup Aug 30 20:18:19 volumio volumio[966]: info: Starting Streaming Daemon Aug 30 20:18:19 volumio volumio[966]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 20:18:19 volumio volumio[966]: info: Removing audio output: Aug 30 20:18:19 volumio volumio[966]: info: Stoppping Tunnel 1 Aug 30 20:18:19 volumio sudo[5081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 20:18:19 volumio sudo[5081]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:18:19 volumio sudo[5081]: pam_unix(sudo:session): session closed for user root Aug 30 20:18:19 volumio volumio[966]: error: Cannot start Volumio Streaming Daemon Aug 30 20:18:19 volumio volumio[966]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 20:18:19 volumio volumio[966]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 20:18:19 volumio sudo[5084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 30 20:18:19 volumio sudo[5084]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:18:19 volumio sudo[5084]: pam_unix(sudo:session): session closed for user root Aug 30 20:18:19 volumio volumio[966]: info: Remote SSH Stopped Aug 30 20:18:13 volumio ntpd[4980]: receive: Unexpected origin timestamp 0xea7c451c.f53c42fb does not match aorg 0000000000.00000000 from server@27.71.27.209 xmt 0xea7c4515.2cc4ee65 Aug 30 20:18:13 volumio volumio[966]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Aug 30 20:18:13 volumio volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 20:18:13 volumio volumio[966]: Error: Unable to resolve or reject the same promise twice Aug 30 20:18:13 volumio volumio[966]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 30 20:18:13 volumio volumio[966]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Aug 30 20:18:13 volumio volumio[966]: at Socket.emit (events.js:412:35) Aug 30 20:18:13 volumio volumio[966]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 30 20:18:13 volumio volumio[966]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 30 20:18:13 volumio volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 20:18:13 volumio sudo[5097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 20:17 Aug 30 20:18:13 volumio sudo[5097]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"