-- Logs begin at Wed 2024-04-24 17:28:26 +07, end at Wed 2024-04-24 17:41:59 +07. -- Apr 24 17:40:00 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:00 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Apr 24 17:40:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:02 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:02 volumio go-librespot[3274]: Librespot-go daemon starting... Apr 24 17:40:02 volumio go-librespot[3274]: time="2024-04-24T17:40:02+07:00" level=info msg="generated new device id: 14936eac90da5fcc2aa41c1ce31911ca5a667eed" Apr 24 17:40:02 volumio go-librespot[3274]: time="2024-04-24T17:40:02+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" Apr 24 17:40:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:03 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:03 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Apr 24 17:40:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:05 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:05 volumio go-librespot[3281]: Librespot-go daemon starting... Apr 24 17:40:05 volumio go-librespot[3281]: time="2024-04-24T17:40:05+07:00" level=info msg="generated new device id: 176e49a6b60a2a591b1e598bab0238bd87d231da" Apr 24 17:40:05 volumio go-librespot[3281]: time="2024-04-24T17:40:05+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" Apr 24 17:40:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:06 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:06 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Apr 24 17:40:08 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:09 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:09 volumio go-librespot[3287]: Librespot-go daemon starting... Apr 24 17:40:09 volumio go-librespot[3287]: time="2024-04-24T17:40:09+07:00" level=info msg="generated new device id: befc9724e07c355a166d969bfdd80f0a665c49bb" Apr 24 17:40:09 volumio go-librespot[3287]: time="2024-04-24T17:40: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" Apr 24 17:40:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:09 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:09 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:12 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:12 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Apr 24 17:40:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:12 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:12 volumio go-librespot[3294]: Librespot-go daemon starting... Apr 24 17:40:12 volumio go-librespot[3294]: time="2024-04-24T17:40:12+07:00" level=info msg="generated new device id: 43496a676c69560b7b97b80f3d1c22c8ca6260fe" Apr 24 17:40:12 volumio go-librespot[3294]: time="2024-04-24T17:40:12+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" Apr 24 17:40:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:15 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:15 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Apr 24 17:40:15 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:15 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:15 volumio go-librespot[3301]: Librespot-go daemon starting... Apr 24 17:40:15 volumio go-librespot[3301]: time="2024-04-24T17:40:15+07:00" level=info msg="generated new device id: 447860d45046e8a93cc8dc05868e629676132d38" Apr 24 17:40:15 volumio go-librespot[3301]: time="2024-04-24T17:40:15+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" Apr 24 17:40:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:18 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:18 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Apr 24 17:40:18 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:18 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:18 volumio go-librespot[3308]: Librespot-go daemon starting... Apr 24 17:40:18 volumio go-librespot[3308]: time="2024-04-24T17:40:18+07:00" level=info msg="generated new device id: 04ba027104304b1c2b81053950a5925e102719ac" Apr 24 17:40:18 volumio go-librespot[3308]: time="2024-04-24T17:40:18+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" Apr 24 17:40:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:21 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:21 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Apr 24 17:40:21 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:22 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:22 volumio go-librespot[3314]: Librespot-go daemon starting... Apr 24 17:40:22 volumio go-librespot[3314]: time="2024-04-24T17:40:22+07:00" level=info msg="generated new device id: a0cfea4a81983a24ec1f1272665fb9bb1c271abf" Apr 24 17:40:22 volumio go-librespot[3314]: time="2024-04-24T17:40:22+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" Apr 24 17:40:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:24 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:24 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:24 volumio ntpd[640]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 24 17:40:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Apr 24 17:40:25 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:25 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:25 volumio go-librespot[3320]: Librespot-go daemon starting... Apr 24 17:40:25 volumio go-librespot[3320]: time="2024-04-24T17:40:25+07:00" level=info msg="generated new device id: e3a318712ba220151253dcba0abcde8a22fa0c01" Apr 24 17:40:25 volumio go-librespot[3320]: time="2024-04-24T17:40:25+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" Apr 24 17:40:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:27 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:27 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 218. Apr 24 17:40:28 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:28 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:28 volumio go-librespot[3327]: Librespot-go daemon starting... Apr 24 17:40:28 volumio go-librespot[3327]: time="2024-04-24T17:40:28+07:00" level=info msg="generated new device id: 8e94271a54b1885ef9e491155e6ca8884113d9ec" Apr 24 17:40:28 volumio go-librespot[3327]: time="2024-04-24T17:40:28+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" Apr 24 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:30 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:30 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:30 volumio ntpd[640]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 24 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219. Apr 24 17:40:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:31 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:31 volumio go-librespot[3378]: Librespot-go daemon starting... Apr 24 17:40:31 volumio go-librespot[3378]: time="2024-04-24T17:40:31+07:00" level=info msg="generated new device id: c843e7244da76ad0d25b1e6054a3005b29bada31" Apr 24 17:40:31 volumio go-librespot[3378]: time="2024-04-24T17:40:31+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" Apr 24 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:33 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:33 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 220. Apr 24 17:40:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:35 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:35 volumio go-librespot[3384]: Librespot-go daemon starting... Apr 24 17:40:35 volumio go-librespot[3384]: time="2024-04-24T17:40:35+07:00" level=info msg="generated new device id: 3e48649289c51eb0907357e8f6e91b76e53240ca" Apr 24 17:40:35 volumio go-librespot[3384]: time="2024-04-24T17:40:35+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" Apr 24 17:40:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:35 volumio ntpd[640]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 24 17:40:36 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:36 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:37 volumio ntpd[640]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 24 17:40:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 221. Apr 24 17:40:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:38 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:38 volumio go-librespot[3390]: Librespot-go daemon starting... Apr 24 17:40:38 volumio go-librespot[3390]: time="2024-04-24T17:40:38+07:00" level=info msg="generated new device id: 7d7e3a615ab8bf82c9f56b86d77632f94439d5f8" Apr 24 17:40:38 volumio go-librespot[3390]: time="2024-04-24T17:40:38+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" Apr 24 17:40:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:39 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:39 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 222. Apr 24 17:40:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:41 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:41 volumio go-librespot[3397]: Librespot-go daemon starting... Apr 24 17:40:41 volumio go-librespot[3397]: time="2024-04-24T17:40:41+07:00" level=info msg="generated new device id: c9c4d8bf9893c15cdcd013a13f713527848483fb" Apr 24 17:40:41 volumio go-librespot[3397]: time="2024-04-24T17:40:41+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" Apr 24 17:40:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:42 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:42 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 223. Apr 24 17:40:44 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:44 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:44 volumio go-librespot[3404]: Librespot-go daemon starting... Apr 24 17:40:44 volumio go-librespot[3404]: time="2024-04-24T17:40:44+07:00" level=info msg="generated new device id: 61572738148e5e93cc900759a077ed708af77590" Apr 24 17:40:44 volumio go-librespot[3404]: time="2024-04-24T17:40:44+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" Apr 24 17:40:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:45 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:45 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 224. Apr 24 17:40:47 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:48 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:48 volumio go-librespot[3411]: Librespot-go daemon starting... Apr 24 17:40:48 volumio go-librespot[3411]: time="2024-04-24T17:40:48+07:00" level=info msg="generated new device id: 41c282b2aee7f7408e5c2ae5994bdec070729c95" Apr 24 17:40:48 volumio go-librespot[3411]: time="2024-04-24T17:40:48+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" Apr 24 17:40:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:48 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:48 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 225. Apr 24 17:40:51 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:51 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:51 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:51 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:51 volumio go-librespot[3418]: Librespot-go daemon starting... Apr 24 17:40:51 volumio go-librespot[3418]: time="2024-04-24T17:40:51+07:00" level=info msg="generated new device id: 867de61065abfd9face19f13f4a52d391871c189" Apr 24 17:40:51 volumio go-librespot[3418]: time="2024-04-24T17:40:51+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" Apr 24 17:40:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:54 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:54 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 226. Apr 24 17:40:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:54 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:54 volumio go-librespot[3424]: Librespot-go daemon starting... Apr 24 17:40:54 volumio go-librespot[3424]: time="2024-04-24T17:40:54+07:00" level=info msg="generated new device id: a333e9ed21e98dc3f0b82fbf5be8dc261c1567f4" Apr 24 17:40:54 volumio go-librespot[3424]: time="2024-04-24T17:40:54+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" Apr 24 17:40:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:40:57 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:40:57 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:40:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:40:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 227. Apr 24 17:40:57 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:40:57 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:40:57 volumio go-librespot[3431]: Librespot-go daemon starting... Apr 24 17:40:57 volumio go-librespot[3431]: time="2024-04-24T17:40:57+07:00" level=info msg="generated new device id: 625fdfe77b787861bc7bf6ebf8a3ec36412d27b3" Apr 24 17:40:57 volumio go-librespot[3431]: time="2024-04-24T17:40:57+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" Apr 24 17:40:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:40:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:00 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:00 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 228. Apr 24 17:41:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:01 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:01 volumio go-librespot[3438]: Librespot-go daemon starting... Apr 24 17:41:01 volumio go-librespot[3438]: time="2024-04-24T17:41:01+07:00" level=info msg="generated new device id: 9e695863b3e670c5a6523b41e93370805b60d007" Apr 24 17:41:01 volumio go-librespot[3438]: time="2024-04-24T17:41: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" Apr 24 17:41:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:03 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:03 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 229. Apr 24 17:41:04 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:04 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:04 volumio go-librespot[3444]: Librespot-go daemon starting... Apr 24 17:41:04 volumio go-librespot[3444]: time="2024-04-24T17:41:04+07:00" level=info msg="generated new device id: 12461176e1ed1a782bbfadeb351a6a1d3b0374e0" Apr 24 17:41:04 volumio go-librespot[3444]: time="2024-04-24T17:41: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" Apr 24 17:41:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:06 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:06 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 230. Apr 24 17:41:07 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:07 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:07 volumio go-librespot[3451]: Librespot-go daemon starting... Apr 24 17:41:07 volumio go-librespot[3451]: time="2024-04-24T17:41:07+07:00" level=info msg="generated new device id: fbd1a3349c03fb88cebf7c51f65641b9215b7750" Apr 24 17:41:07 volumio go-librespot[3451]: time="2024-04-24T17:41:07+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" Apr 24 17:41:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:09 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:09 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 231. Apr 24 17:41:10 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:10 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:10 volumio go-librespot[3457]: Librespot-go daemon starting... Apr 24 17:41:10 volumio go-librespot[3457]: time="2024-04-24T17:41:10+07:00" level=info msg="generated new device id: dac4d8df5f7862266b67dac38492cf5424dc2651" Apr 24 17:41:10 volumio go-librespot[3457]: time="2024-04-24T17:41:10+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" Apr 24 17:41:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:12 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:12 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 232. Apr 24 17:41:13 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:13 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:14 volumio go-librespot[3464]: Librespot-go daemon starting... Apr 24 17:41:14 volumio go-librespot[3464]: time="2024-04-24T17:41:14+07:00" level=info msg="generated new device id: 6012a3c4b6a006f2f433aa5187750d5df32612ed" Apr 24 17:41:14 volumio go-librespot[3464]: time="2024-04-24T17:41: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" Apr 24 17:41:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:15 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:15 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233. Apr 24 17:41:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:17 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:17 volumio go-librespot[3471]: Librespot-go daemon starting... Apr 24 17:41:17 volumio go-librespot[3471]: time="2024-04-24T17:41:17+07:00" level=info msg="generated new device id: c10fe6fee2664b423535dbf680eda85af1f418fe" Apr 24 17:41:17 volumio go-librespot[3471]: time="2024-04-24T17:41: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" Apr 24 17:41:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:18 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:18 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 234. Apr 24 17:41:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:20 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:20 volumio go-librespot[3478]: Librespot-go daemon starting... Apr 24 17:41:20 volumio go-librespot[3478]: time="2024-04-24T17:41:20+07:00" level=info msg="generated new device id: 156c14ed62654c72b829ddb1f02824ed82411afd" Apr 24 17:41:20 volumio go-librespot[3478]: time="2024-04-24T17:41:20+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" Apr 24 17:41:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:21 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:21 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 235. Apr 24 17:41:23 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:23 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:23 volumio go-librespot[3485]: Librespot-go daemon starting... Apr 24 17:41:23 volumio go-librespot[3485]: time="2024-04-24T17:41:23+07:00" level=info msg="generated new device id: 44f2543b7f3127fae534c22bbaf8ba55d19363d6" Apr 24 17:41:23 volumio go-librespot[3485]: time="2024-04-24T17:41:23+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" Apr 24 17:41:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:24 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:24 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 236. Apr 24 17:41:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:27 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:27 volumio go-librespot[3492]: Librespot-go daemon starting... Apr 24 17:41:27 volumio go-librespot[3492]: time="2024-04-24T17:41:27+07:00" level=info msg="generated new device id: c19168565e66f5383f7735b820597a53fa5a33bc" Apr 24 17:41:27 volumio go-librespot[3492]: time="2024-04-24T17:41: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" Apr 24 17:41:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:27 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:27 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 237. Apr 24 17:41:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:30 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:30 volumio go-librespot[3499]: Librespot-go daemon starting... Apr 24 17:41:30 volumio go-librespot[3499]: time="2024-04-24T17:41:30+07:00" level=info msg="generated new device id: 3d7eb84d7f16d1d46fff4562003f6cdaf8f7be35" Apr 24 17:41:30 volumio go-librespot[3499]: time="2024-04-24T17:41: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" Apr 24 17:41:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:30 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:30 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:30 volumio ntpd[640]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 24 17:41:33 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:33 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 238. Apr 24 17:41:33 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:33 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:33 volumio go-librespot[3551]: Librespot-go daemon starting... Apr 24 17:41:33 volumio go-librespot[3551]: time="2024-04-24T17:41:33+07:00" level=info msg="generated new device id: 9c540e1681665c392e05844fdc6bbf93d8d15f61" Apr 24 17:41:33 volumio go-librespot[3551]: time="2024-04-24T17:41:33+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" Apr 24 17:41:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:34 volumio ntpd[640]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 24 17:41:36 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:36 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 239. Apr 24 17:41:36 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:36 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:36 volumio go-librespot[3558]: Librespot-go daemon starting... Apr 24 17:41:36 volumio go-librespot[3558]: time="2024-04-24T17:41:36+07:00" level=info msg="generated new device id: 8371b7c650439b965201acea934adf351a53c9d4" Apr 24 17:41:36 volumio go-librespot[3558]: time="2024-04-24T17:41:36+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" Apr 24 17:41:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:38 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Apr 24 17:41:38 volumio dhcpcd[526]: eth0: carrier acquired Apr 24 17:41:38 volumio dhcpcd[526]: eth0: IAID d8:04:3a:04 Apr 24 17:41:38 volumio dhcpcd[526]: eth0: soliciting a DHCP lease Apr 24 17:41:39 volumio ifplugd(eth0)[628]: Link beat detected. Apr 24 17:41:39 volumio dhcpcd[526]: eth0: soliciting an IPv6 router Apr 24 17:41:39 volumio ifplugd(eth0)[628]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 24 17:41:39 volumio ifplugd(eth0)[628]: client: command failed: No such device (-19) Apr 24 17:41:39 volumio dhcpcd[3588]: sending commands to master dhcpcd process Apr 24 17:41:39 volumio dhcpcd[526]: control command: /sbin/dhcpcd eth0 Apr 24 17:41:39 volumio ifplugd(eth0)[628]: client: sending commands to master dhcpcd process Apr 24 17:41:39 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:39 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:39 volumio ifplugd(eth0)[628]: Program executed successfully. Apr 24 17:41:39 volumio ntpd[640]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 24 17:41:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 240. Apr 24 17:41:39 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:40 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:40 volumio go-librespot[3614]: Librespot-go daemon starting... Apr 24 17:41:40 volumio go-librespot[3614]: time="2024-04-24T17:41:40+07:00" level=info msg="generated new device id: 59476af5a91dc59310d1bf92a0c51a8525f972dd" Apr 24 17:41:40 volumio go-librespot[3614]: time="2024-04-24T17:41: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" Apr 24 17:41:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:42 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:42 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:42 volumio ntpd[640]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 24 17:41:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 241. Apr 24 17:41:43 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:43 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:43 volumio go-librespot[3620]: Librespot-go daemon starting... Apr 24 17:41:43 volumio go-librespot[3620]: time="2024-04-24T17:41:43+07:00" level=info msg="generated new device id: 05d974cf715d46365b9a300c7a7137cc9a40b6ef" Apr 24 17:41:43 volumio go-librespot[3620]: time="2024-04-24T17:41: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" Apr 24 17:41:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:43 volumio dhcpcd[526]: eth0: probing for an IPv4LL address Apr 24 17:41:45 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:45 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Apr 24 17:41:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:46 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:46 volumio go-librespot[3627]: Librespot-go daemon starting... Apr 24 17:41:46 volumio go-librespot[3627]: time="2024-04-24T17:41:46+07:00" level=info msg="generated new device id: e7b940f87f53307914196ae29bd28a46cfce5225" Apr 24 17:41:46 volumio go-librespot[3627]: time="2024-04-24T17:41:46+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" Apr 24 17:41:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:41:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:41:46 volumio dhcpcd[526]: eth0: offered 192.168.1.20 from 192.168.1.1 Apr 24 17:41:46 volumio dhcpcd[526]: eth0: probing address 192.168.1.20/24 Apr 24 17:41:48 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:48 volumio volumio[663]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:41:48 volumio dhcpcd[526]: eth0: using IPv4LL address 169.254.167.244 Apr 24 17:41:48 volumio dhcpcd[526]: eth0: adding route to 169.254.0.0/16 Apr 24 17:41:48 volumio dhcpcd[526]: eth0: adding default route Apr 24 17:41:48 volumio avahi-daemon[457]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.167.244. Apr 24 17:41:48 volumio avahi-daemon[457]: New relevant interface eth0.IPv4 for mDNS. Apr 24 17:41:48 volumio avahi-daemon[457]: Registering new address record for 169.254.167.244 on eth0.IPv4. Apr 24 17:41:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:41:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Apr 24 17:41:49 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 17:41:49 volumio systemd[1]: Started go-librespot Daemon. Apr 24 17:41:49 volumio go-librespot[3647]: Librespot-go daemon starting... Apr 24 17:41:49 volumio go-librespot[3647]: time="2024-04-24T17:41:49+07:00" level=info msg="generated new device id: 45fa5f3f0416bca65f71fe1431ba22369e1d55a6" Apr 24 17:41:49 volumio ntpd[640]: Listen normally on 3 eth0 169.254.167.244:123 Apr 24 17:41:49 volumio ntpd[640]: new interface(s) found: waking up resolver Apr 24 17:41:51 volumio volumio[663]: info: Initializing connection to go-librespot Websocket Apr 24 17:41:51 volumio go-librespot[3647]: time="2024-04-24T17:41:51+07:00" level=debug msg="new websocket client" Apr 24 17:41:51 volumio volumio[663]: info: Connection to go-librespot Websocket established Apr 24 17:41:51 volumio dhcpcd[526]: eth0: leased 192.168.1.20 for 3600 seconds Apr 24 17:41:51 volumio dhcpcd[526]: eth0: adding route to 192.168.1.0/24 Apr 24 17:41:51 volumio dhcpcd[526]: eth0: changing default route via 192.168.1.1 Apr 24 17:41:51 volumio avahi-daemon[457]: Registering new address record for 192.168.1.20 on eth0.IPv4. Apr 24 17:41:51 volumio ntpd[640]: ntpd exiting on signal 15 (Terminated) Apr 24 17:41:51 volumio systemd[1]: Stopping Network Time Service... Apr 24 17:41:51 volumio systemd[1]: ntp.service: Succeeded. Apr 24 17:41:51 volumio systemd[1]: Stopped Network Time Service. Apr 24 17:41:51 volumio systemd[1]: Starting Network Time Service... Apr 24 17:41:51 volumio ntpd[3687]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 24 17:41:51 volumio ntpd[3687]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 24 17:41:51 volumio systemd[1]: Started Network Time Service. Apr 24 17:41:51 volumio ntpd[3693]: proto: precision = 0.192 usec (-22) Apr 24 17:41:51 volumio dhcpcd[526]: eth0: deleting route to 169.254.0.0/16 Apr 24 17:41:51 volumio ntpd[3693]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 24 17:41:51 volumio avahi-daemon[457]: Withdrawing address record for 169.254.167.244 on eth0. Apr 24 17:41:51 volumio avahi-daemon[457]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.167.244. Apr 24 17:41:51 volumio avahi-daemon[457]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.20. Apr 24 17:41:51 volumio ntpd[3693]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 24 17:41:51 volumio ntpd[3693]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 484 days ago Apr 24 17:41:51 volumio ntpd[3693]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 17:41:51 volumio ntpd[3693]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 17:41:51 volumio ntpd[3693]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 17:41:51 volumio ntpd[3693]: Listen normally on 3 eth0 192.168.1.20:123 Apr 24 17:41:51 volumio ntpd[3693]: Listening on routing socket on fd #20 for interface updates Apr 24 17:41:51 volumio ntpd[3693]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 17:41:51 volumio ntpd[3693]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 17:41:53 volumio ntpd[3693]: Soliciting pool server 203.113.174.44 Apr 24 17:41:53 volumio ntpd[3693]: Soliciting pool server 103.130.217.41 Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin bluetooth to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin multiroom to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin metavolumio to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin cd_controller to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 24 17:41:54 volumio volumio[663]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 24 17:41:55 volumio ntpd[3693]: Soliciting pool server 2400:8904:e001:18d:0:a789:b456:c123 Apr 24 17:41:55 volumio go-librespot[3647]: time="2024-04-24T17:41:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 24 17:41:55 volumio go-librespot[3647]: time="2024-04-24T17:41:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 24 17:41:55 volumio go-librespot[3647]: time="2024-04-24T17:41:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 24 17:41:55 volumio go-librespot[3647]: time="2024-04-24T17:41:55+07:00" level=debug msg="zeroconf server listening on port 34217" Apr 24 17:41:55 volumio volumio[663]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 24 17:41:55 volumio volumio[663]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 24 17:41:55 volumio volumio[663]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:41:55 volumio volumio[663]: info: Starting MyVolumio Remote Streaming Endpoints Apr 24 17:41:55 volumio volumio[663]: info: MyVolumio login type: Token Apr 24 17:41:55 volumio volumio[663]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 24 17:41:55 volumio volumio[663]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 24 17:41:56 volumio volumio[663]: info: Starting Streaming Service Transparent Proxy Apr 24 17:41:56 volumio volumio[663]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 24 17:41:56 volumio volumio[663]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 24 17:41:56 volumio volumio[663]: info: Streaming services startup Apr 24 17:41:56 volumio volumio[663]: info: Starting Streaming Daemon Apr 24 17:41:56 volumio sudo[3719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 24 17:41:56 volumio sudo[3719]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:41:56 volumio volumio[663]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 24 17:41:56 volumio sudo[3719]: pam_unix(sudo:session): session closed for user root Apr 24 17:41:56 volumio volumio[663]: info: Getting Spotify volume Apr 24 17:41:56 volumio volumio[663]: info: Discovery: adding 38f46243-bc98-4671-99a4-14313008c186 Apr 24 17:41:56 volumio volumio[663]: info: Discovery: Found device Volumio Apr 24 17:41:56 volumio volumio[663]: info: CoreCommandRouter::volumioGetState Apr 24 17:41:56 volumio volumio[663]: info: CorePlayQueue::getTrack 0 Apr 24 17:41:56 volumio volumio[663]: error: Cannot start Volumio Streaming Daemon Apr 24 17:41:56 volumio volumio[663]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 24 17:41:56 volumio volumio[663]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 24 17:41:56 volumio volumio[663]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 24 17:41:56 volumio volumio[663]: info: CoreCommandRouter::volumioGetState Apr 24 17:41:56 volumio volumio[663]: info: CorePlayQueue::getTrack 0 Apr 24 17:41:56 volumio volumio[663]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 24 17:41:56 volumio volumio[663]: SPOTIFY: SPOTIFY VOLUME undefined Apr 24 17:41:56 volumio volumio[663]: SPOTIFY: VOLUMIO VOLUME 45 Apr 24 17:41:56 volumio volumio[663]: info: Aligning Spotify Volume to Volumio Volume Apr 24 17:41:56 volumio volumio[663]: info: CoreCommandRouter::volumioGetState Apr 24 17:41:56 volumio volumio[663]: info: CorePlayQueue::getTrack 0 Apr 24 17:41:56 volumio volumio[663]: info: Setting Spotify Volume from Volumio: 45 Apr 24 17:41:56 volumio volumio[663]: error: MyVolumio Custom Token format not valid, refreshing it Apr 24 17:41:56 volumio volumio[663]: STREAMING PROXY: Starting server on port 3245 Apr 24 17:41:56 volumio volumio[663]: Node JS runtime: 14 Apr 24 17:41:57 volumio volumio[663]: info: MyVolumio login type: Token Apr 24 17:41:57 volumio volumio[663]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Apr 24 17:41:57 volumio volumio[663]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:41:58 volumio volumio[663]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 24 17:41:58 volumio volumio[663]: info: MyVolumio token set successfully Apr 24 17:41:58 volumio volumio[663]: info: MYVOLUMIO: Adding device Apr 24 17:41:58 volumio volumio[663]: info: MYVOLUMIO: Evaluating Server Apr 24 17:41:59 volumio volumio[663]: info: MyVolumio status changed Apr 24 17:41:59 volumio volumio[663]: info: Streaming services startup Apr 24 17:41:59 volumio volumio[663]: info: Starting Streaming Daemon Apr 24 17:41:59 volumio volumio[663]: info: Removing browser output: myVolumio user plan is not superstar Apr 24 17:41:59 volumio volumio[663]: info: Removing audio output: Apr 24 17:41:59 volumio volumio[663]: info: Stoppping Tunnel 1 Apr 24 17:41:59 volumio sudo[3744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 24 17:41:59 volumio sudo[3744]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:41:59 volumio sudo[3744]: pam_unix(sudo:session): session closed for user root Apr 24 17:41:59 volumio volumio[663]: error: Cannot start Volumio Streaming Daemon Apr 24 17:41:59 volumio volumio[663]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 24 17:41:59 volumio volumio[663]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 24 17:41:59 volumio sudo[3747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 24 17:41:59 volumio sudo[3747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:41:59 volumio sudo[3747]: pam_unix(sudo:session): session closed for user root Apr 24 17:41:59 volumio volumio[663]: info: Remote SSH Stopped Apr 24 17:41:52 volumio ntpd[3693]: receive: Unexpected origin timestamp 0xe9d36077.fe8214e9 does not match aorg 0000000000.00000000 from server@103.130.217.41 xmt 0xe9d36070.1a466a79 Apr 24 17:41:52 volumio volumio[663]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Apr 24 17:41:52 volumio volumio[663]: info: Setting Geolocation for MyVolumio to as1 Apr 24 17:41:52 volumio volumio[663]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:41:52 volumio volumio[663]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:41:52 volumio volumio[663]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:41:52 volumio volumio[663]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:41:52 volumio volumio[663]: Error: Unable to resolve or reject the same promise twice Apr 24 17:41:52 volumio volumio[663]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 24 17:41:52 volumio volumio[663]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Apr 24 17:41:52 volumio volumio[663]: at Socket.emit (events.js:412:35) Apr 24 17:41:52 volumio volumio[663]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 24 17:41:52 volumio volumio[663]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 24 17:41:52 volumio volumio[663]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:41:52 volumio sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 17:40 Apr 24 17:41:52 volumio sudo[3759]: 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"