-- 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"