-- Logs begin at Thu 2024-02-15 09:21:55 UTC, end at Thu 2024-02-15 11:39:49 UTC. -- Feb 15 11:38:01 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:02 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365422. Feb 15 11:38:02 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:02 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:02 volumio go-librespot[399]: Librespot-go daemon starting... Feb 15 11:38:02 volumio go-librespot[399]: time="2024-02-15T11:38:02Z" level=info msg="generated new device id: c447da69085a0cb398762d487a7a27452663404f" Feb 15 11:38:02 volumio go-librespot[399]: time="2024-02-15T11:38:02Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:02 volumio go-librespot[399]: time="2024-02-15T11:38:02Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:05 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:05 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365423. Feb 15 11:38:05 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:05 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:05 volumio go-librespot[407]: Librespot-go daemon starting... Feb 15 11:38:05 volumio go-librespot[407]: time="2024-02-15T11:38:05Z" level=info msg="generated new device id: f5cb3fdb87d7b9f87853e8e872ee220cdf51f037" Feb 15 11:38:05 volumio go-librespot[407]: time="2024-02-15T11:38:05Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:05 volumio go-librespot[407]: time="2024-02-15T11:38:05Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:08 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:08 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:08 volumio ntpd[525]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 15 11:38:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365424. Feb 15 11:38:08 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:09 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:09 volumio go-librespot[414]: Librespot-go daemon starting... Feb 15 11:38:09 volumio go-librespot[414]: time="2024-02-15T11:38:09Z" level=info msg="generated new device id: ec71ac944f4c7bd7c91fc08b561d7348ae424123" Feb 15 11:38:09 volumio go-librespot[414]: time="2024-02-15T11:38:09Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:09 volumio go-librespot[414]: time="2024-02-15T11:38:09Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:11 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365425. Feb 15 11:38:12 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:12 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:12 volumio go-librespot[421]: Librespot-go daemon starting... Feb 15 11:38:12 volumio go-librespot[421]: time="2024-02-15T11:38:12Z" level=info msg="generated new device id: a1180468c2586c529d026f126c2a149ed3e85f69" Feb 15 11:38:12 volumio go-librespot[421]: time="2024-02-15T11:38:12Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:12 volumio go-librespot[421]: time="2024-02-15T11:38:12Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:12 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:14 volumio ntpd[525]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 15 11:38:15 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:15 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365426. Feb 15 11:38:15 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:15 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:15 volumio go-librespot[428]: Librespot-go daemon starting... Feb 15 11:38:15 volumio go-librespot[428]: time="2024-02-15T11:38:15Z" level=info msg="generated new device id: e323f28a08541908182dbb21387828b614a811e3" Feb 15 11:38:15 volumio go-librespot[428]: time="2024-02-15T11:38:15Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:15 volumio go-librespot[428]: time="2024-02-15T11:38:15Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:18 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:18 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365427. Feb 15 11:38:18 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:18 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:18 volumio go-librespot[435]: Librespot-go daemon starting... Feb 15 11:38:18 volumio go-librespot[435]: time="2024-02-15T11:38:18Z" level=info msg="generated new device id: af84436cdf65b89b5da3bf1a8bbe52412df5e4a8" Feb 15 11:38:18 volumio go-librespot[435]: time="2024-02-15T11:38:18Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:18 volumio go-librespot[435]: time="2024-02-15T11:38:18Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:21 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365428. Feb 15 11:38:21 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:22 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:22 volumio go-librespot[442]: Librespot-go daemon starting... Feb 15 11:38:22 volumio go-librespot[442]: time="2024-02-15T11:38:22Z" level=info msg="generated new device id: 64731c2ba3d2fcdf0031b5e9bb503e65c0613472" Feb 15 11:38:22 volumio go-librespot[442]: time="2024-02-15T11:38:22Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:22 volumio go-librespot[442]: time="2024-02-15T11:38:22Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:22 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:22 volumio ntpd[525]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 15 11:38:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365429. Feb 15 11:38:25 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:25 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:25 volumio go-librespot[449]: Librespot-go daemon starting... Feb 15 11:38:25 volumio go-librespot[449]: time="2024-02-15T11:38:25Z" level=info msg="generated new device id: d54b206c8bbee3bbccb953b73b1e2ef860aca85c" Feb 15 11:38:25 volumio go-librespot[449]: time="2024-02-15T11:38:25Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:25 volumio go-librespot[449]: time="2024-02-15T11:38:25Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:25 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:25 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365430. Feb 15 11:38:28 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:28 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:28 volumio go-librespot[457]: Librespot-go daemon starting... Feb 15 11:38:28 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:28 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:28 volumio go-librespot[457]: time="2024-02-15T11:38:28Z" level=info msg="generated new device id: debf6a82d88b37bc0eebde524a2d3ed727ac4ee5" Feb 15 11:38:28 volumio go-librespot[457]: time="2024-02-15T11:38:28Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:28 volumio go-librespot[457]: time="2024-02-15T11:38:28Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:31 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365431. Feb 15 11:38:31 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:31 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:31 volumio go-librespot[464]: Librespot-go daemon starting... Feb 15 11:38:31 volumio go-librespot[464]: time="2024-02-15T11:38:31Z" level=info msg="generated new device id: 6376b90a302f53fbdfb534fb31ee55f007c81c94" Feb 15 11:38:31 volumio go-librespot[464]: time="2024-02-15T11:38:31Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:31 volumio go-librespot[464]: time="2024-02-15T11:38:31Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:32 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365432. Feb 15 11:38:34 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:35 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:35 volumio go-librespot[471]: Librespot-go daemon starting... Feb 15 11:38:35 volumio go-librespot[471]: time="2024-02-15T11:38:35Z" level=info msg="generated new device id: 2677d055505ce28142d34b0405b13d9e369b83f8" Feb 15 11:38:35 volumio go-librespot[471]: time="2024-02-15T11:38:35Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:35 volumio go-librespot[471]: time="2024-02-15T11:38:35Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:35 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:35 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365433. Feb 15 11:38:38 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:38 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:38 volumio go-librespot[478]: Librespot-go daemon starting... Feb 15 11:38:38 volumio go-librespot[478]: time="2024-02-15T11:38:38Z" level=info msg="generated new device id: a82e8d178c0a2c41b0da9249b2292b5c452c7922" Feb 15 11:38:38 volumio go-librespot[478]: time="2024-02-15T11:38:38Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:38 volumio go-librespot[478]: time="2024-02-15T11:38:38Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:38 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:38 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365434. Feb 15 11:38:41 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:41 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:41 volumio go-librespot[487]: Librespot-go daemon starting... Feb 15 11:38:41 volumio go-librespot[487]: time="2024-02-15T11:38:41Z" level=info msg="generated new device id: bf1844c8426f4ff5ef59ca3c8b0718d9802850b5" Feb 15 11:38:41 volumio go-librespot[487]: time="2024-02-15T11:38:41Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:41 volumio go-librespot[487]: time="2024-02-15T11:38:41Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:41 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:42 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365435. Feb 15 11:38:44 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:44 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:44 volumio go-librespot[495]: Librespot-go daemon starting... Feb 15 11:38:44 volumio go-librespot[495]: time="2024-02-15T11:38:44Z" level=info msg="generated new device id: aa959418e16024881de4377c7ba8f870b7c1ec43" Feb 15 11:38:44 volumio go-librespot[495]: time="2024-02-15T11:38:44Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:44 volumio go-librespot[495]: time="2024-02-15T11:38:44Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:45 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:45 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365436. Feb 15 11:38:47 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:48 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:48 volumio go-librespot[502]: Librespot-go daemon starting... Feb 15 11:38:48 volumio go-librespot[502]: time="2024-02-15T11:38:48Z" level=info msg="generated new device id: 8e76d6007d5506815f670db4e2fb2a24663feb3f" Feb 15 11:38:48 volumio go-librespot[502]: time="2024-02-15T11:38:48Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:48 volumio go-librespot[502]: time="2024-02-15T11:38:48Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:48 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:48 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365437. Feb 15 11:38:51 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:51 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:51 volumio go-librespot[509]: Librespot-go daemon starting... Feb 15 11:38:51 volumio go-librespot[509]: time="2024-02-15T11:38:51Z" level=info msg="generated new device id: 3f7117842af4a20f37af4ba48ecf4af24ae15ed9" Feb 15 11:38:51 volumio go-librespot[509]: time="2024-02-15T11:38:51Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:51 volumio go-librespot[509]: time="2024-02-15T11:38:51Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:51 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:52 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365438. Feb 15 11:38:54 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:54 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:54 volumio go-librespot[519]: Librespot-go daemon starting... Feb 15 11:38:54 volumio go-librespot[519]: time="2024-02-15T11:38:54Z" level=info msg="generated new device id: 95502364ff3746cc8eb62c3e6b2924068c07e341" Feb 15 11:38:54 volumio go-librespot[519]: time="2024-02-15T11:38:54Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:54 volumio go-librespot[519]: time="2024-02-15T11:38:54Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:55 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:55 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:38:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:38:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365439. Feb 15 11:38:57 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:38:57 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:38:57 volumio go-librespot[530]: Librespot-go daemon starting... Feb 15 11:38:57 volumio go-librespot[530]: time="2024-02-15T11:38:57Z" level=info msg="generated new device id: 4e9d32b9a516b6b31ca104362b5696022e8f233d" Feb 15 11:38:57 volumio go-librespot[530]: time="2024-02-15T11:38:57Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:38:57 volumio go-librespot[530]: time="2024-02-15T11:38:57Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:38:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:38:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:38:58 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:38:58 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365440. Feb 15 11:39:00 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:01 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:01 volumio go-librespot[537]: Librespot-go daemon starting... Feb 15 11:39:01 volumio go-librespot[537]: time="2024-02-15T11:39:01Z" level=info msg="generated new device id: 43d2754ae51d246554304bfd6c5d6543ad01dc9b" Feb 15 11:39:01 volumio go-librespot[537]: time="2024-02-15T11:39:01Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:01 volumio go-librespot[537]: time="2024-02-15T11:39:01Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:01 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:02 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365441. Feb 15 11:39:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:04 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:04 volumio go-librespot[561]: Librespot-go daemon starting... Feb 15 11:39:04 volumio go-librespot[561]: time="2024-02-15T11:39:04Z" level=info msg="generated new device id: 9f12466b9406e3867819724df13d88a116fa2a28" Feb 15 11:39:04 volumio go-librespot[561]: time="2024-02-15T11:39:04Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:04 volumio go-librespot[561]: time="2024-02-15T11:39:04Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:04 volumio ntpd[525]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 15 11:39:05 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:05 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365442. Feb 15 11:39:07 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:07 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:07 volumio go-librespot[568]: Librespot-go daemon starting... Feb 15 11:39:07 volumio go-librespot[568]: time="2024-02-15T11:39:07Z" level=info msg="generated new device id: d32c4e3b52d3c551e9d82e8292bcb95727b91694" Feb 15 11:39:07 volumio go-librespot[568]: time="2024-02-15T11:39:07Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:07 volumio go-librespot[568]: time="2024-02-15T11:39:07Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:08 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:08 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365443. Feb 15 11:39:10 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:10 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:10 volumio go-librespot[575]: Librespot-go daemon starting... Feb 15 11:39:10 volumio go-librespot[575]: time="2024-02-15T11:39:10Z" level=info msg="generated new device id: 1f6c4286d6ed50dba2ab718bddd29941626c7666" Feb 15 11:39:10 volumio go-librespot[575]: time="2024-02-15T11:39:10Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:10 volumio go-librespot[575]: time="2024-02-15T11:39:10Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:11 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:12 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365444. Feb 15 11:39:13 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:14 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:14 volumio go-librespot[583]: Librespot-go daemon starting... Feb 15 11:39:14 volumio go-librespot[583]: time="2024-02-15T11:39:14Z" level=info msg="generated new device id: 28002925ff70bb4b11520148aad5a89efaab1e9d" Feb 15 11:39:14 volumio go-librespot[583]: time="2024-02-15T11:39:14Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:14 volumio go-librespot[583]: time="2024-02-15T11:39:14Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:15 volumio ntpd[525]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 15 11:39:15 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:15 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365445. Feb 15 11:39:17 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:17 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:17 volumio go-librespot[590]: Librespot-go daemon starting... Feb 15 11:39:17 volumio go-librespot[590]: time="2024-02-15T11:39:17Z" level=info msg="generated new device id: f1d65995ea53b31ed9a0671da920e0b766113234" Feb 15 11:39:17 volumio go-librespot[590]: time="2024-02-15T11:39:17Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:17 volumio go-librespot[590]: time="2024-02-15T11:39:17Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:18 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:18 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365446. Feb 15 11:39:20 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:20 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:20 volumio go-librespot[597]: Librespot-go daemon starting... Feb 15 11:39:20 volumio go-librespot[597]: time="2024-02-15T11:39:20Z" level=info msg="generated new device id: 5614cdad5b1f8eb2fa4aa5b16a7fbc03f14d346d" Feb 15 11:39:20 volumio go-librespot[597]: time="2024-02-15T11:39:20Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:20 volumio go-librespot[597]: time="2024-02-15T11:39:20Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:21 volumio ntpd[525]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 15 11:39:21 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:22 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365447. Feb 15 11:39:23 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:23 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:23 volumio go-librespot[618]: Librespot-go daemon starting... Feb 15 11:39:23 volumio go-librespot[618]: time="2024-02-15T11:39:23Z" level=info msg="generated new device id: be1685cbaa610a30179570b2b8a88263d6b1b633" Feb 15 11:39:23 volumio go-librespot[618]: time="2024-02-15T11:39:23Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:23 volumio go-librespot[618]: time="2024-02-15T11:39:23Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:25 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 15 11:39:25 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:25 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:25 volumio ifplugd(eth0)[521]: Link beat detected. Feb 15 11:39:25 volumio ifplugd(eth0)[521]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 15 11:39:25 volumio ifplugd(eth0)[521]: client: command failed: No such device (-19) Feb 15 11:39:26 volumio dhcpcd[667]: DUID 00:01:00:01:2a:5e:eb:f3:b8:27:eb:bb:07:59 Feb 15 11:39:26 volumio dhcpcd[667]: eth0: IAID eb:bb:07:59 Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: DUID 00:01:00:01:2a:5e:eb:f3:b8:27:eb:bb:07:59 Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: IAID eb:bb:07:59 Feb 15 11:39:26 volumio dhcpcd[667]: eth0: adding address fe80::62c:b4b9:3b27:68fa Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: adding address fe80::62c:b4b9:3b27:68fa Feb 15 11:39:26 volumio dhcpcd[667]: ipv6_addaddr1: Permission denied Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: ipv6_addaddr1: Permission denied Feb 15 11:39:26 volumio dhcpcd[667]: eth0: rebinding lease of 192.168.55.160 Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: rebinding lease of 192.168.55.160 Feb 15 11:39:26 volumio dhcpcd[667]: eth0: NAK: from 192.168.15.1 Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: NAK: from 192.168.15.1 Feb 15 11:39:26 volumio dhcpcd[667]: eth0: soliciting a DHCP lease Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: soliciting a DHCP lease Feb 15 11:39:26 volumio dhcpcd[667]: eth0: soliciting an IPv6 router Feb 15 11:39:26 volumio ifplugd(eth0)[521]: client: eth0: soliciting an IPv6 router Feb 15 11:39:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365448. Feb 15 11:39:26 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:27 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:27 volumio go-librespot[679]: Librespot-go daemon starting... Feb 15 11:39:27 volumio go-librespot[679]: time="2024-02-15T11:39:27Z" level=info msg="generated new device id: c680be373b327e39401a4b2e8c33da24f440d28d" Feb 15 11:39:27 volumio go-librespot[679]: time="2024-02-15T11:39:27Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:27 volumio go-librespot[679]: time="2024-02-15T11:39:27Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:28 volumio dhcpcd[667]: eth0: offered 192.168.15.9 from 192.168.15.1 Feb 15 11:39:28 volumio ifplugd(eth0)[521]: client: eth0: offered 192.168.15.9 from 192.168.15.1 Feb 15 11:39:28 volumio dhcpcd[667]: eth0: probing address 192.168.15.9/24 Feb 15 11:39:28 volumio ifplugd(eth0)[521]: client: eth0: probing address 192.168.15.9/24 Feb 15 11:39:28 volumio ntpd[525]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 15 11:39:28 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:28 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365449. Feb 15 11:39:30 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:30 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:30 volumio go-librespot[687]: Librespot-go daemon starting... Feb 15 11:39:30 volumio go-librespot[687]: time="2024-02-15T11:39:30Z" level=info msg="generated new device id: db4cd6de96dc09a58675a4771c093f0d2979b1d3" Feb 15 11:39:30 volumio go-librespot[687]: time="2024-02-15T11:39:30Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:30 volumio go-librespot[687]: time="2024-02-15T11:39:30Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 15 11:39:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 15 11:39:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 15 11:39:31 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:31 volumio volumio[539]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 15 11:39:33 volumio dhcpcd[667]: eth0: leased 192.168.15.9 for 86400 seconds Feb 15 11:39:33 volumio ifplugd(eth0)[521]: client: eth0: leased 192.168.15.9 for 86400 seconds Feb 15 11:39:33 volumio dhcpcd[667]: eth0: adding route to 192.168.15.0/24 Feb 15 11:39:33 volumio dhcpcd[667]: eth0: adding default route via 192.168.15.1 Feb 15 11:39:33 volumio ifplugd(eth0)[521]: client: eth0: adding route to 192.168.15.0/24 Feb 15 11:39:33 volumio ifplugd(eth0)[521]: client: eth0: adding default route via 192.168.15.1 Feb 15 11:39:33 volumio avahi-daemon[368]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.15.9. Feb 15 11:39:33 volumio avahi-daemon[368]: New relevant interface eth0.IPv4 for mDNS. Feb 15 11:39:33 volumio avahi-daemon[368]: Registering new address record for 192.168.15.9 on eth0.IPv4. Feb 15 11:39:33 volumio dhcpcd[667]: forked to background, child pid 727 Feb 15 11:39:33 volumio ifplugd(eth0)[521]: client: forked to background, child pid 727 Feb 15 11:39:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 15 11:39:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3365450. Feb 15 11:39:33 volumio systemd[1]: Stopped go-librespot Daemon. Feb 15 11:39:33 volumio systemd[1]: Started go-librespot Daemon. Feb 15 11:39:33 volumio go-librespot[752]: Librespot-go daemon starting... Feb 15 11:39:33 volumio go-librespot[752]: time="2024-02-15T11:39:33Z" level=info msg="generated new device id: caa4a665996218766809fabf2b3aaab89d46f4e7" Feb 15 11:39:33 volumio go-librespot[752]: time="2024-02-15T11:39:33Z" level=debug msg="stored credentials found for 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:33 volumio ifplugd(eth0)[521]: Program executed successfully. Feb 15 11:39:34 volumio volumio[539]: info: Discovery: adding 4f7b476c-343c-4c76-bbef-0fe28ae60fec Feb 15 11:39:34 volumio volumio[539]: info: mDNS: Found device Volumio Feb 15 11:39:34 volumio volumio[539]: info: CoreCommandRouter::volumioGetState Feb 15 11:39:34 volumio volumio[539]: info: CorePlayQueue::getTrack 0 Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="obtained new client token: AAC17vt4Luf9chFVv519kW9XBcNYcJi0h+oVVSwZ55+LDAMECOsw5kz12zJszmWuS4Zu1Q1VVfSsJLU3qDHUiKE3PoLt3ZrVXtY32t9hGDK9JO4c0T9SOAIDSqVzJDuGjYGqkLa1wH054TsJO/pizOc6SXdN0fE/KbqEZr7v81Yz2DZxSKeQb82gRDyQQt/jsI2MuoWclhUeiHPypWYu8clyKDeVW5FRLazuV1CIMFPXPbrcnHFcFyyA4CtLxGQ=" Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="completed keyexchange" Feb 15 11:39:34 volumio ntpd[525]: Listen normally on 3 eth0 192.168.15.9:123 Feb 15 11:39:34 volumio ntpd[525]: new interface(s) found: waking up resolver Feb 15 11:39:34 volumio volumio[539]: info: Initializing connection to go-librespot Websocket Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="new websocket client" Feb 15 11:39:34 volumio volumio[539]: info: Connection to go-librespot Websocket established Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="completed challenge" Feb 15 11:39:34 volumio go-librespot[752]: time="2024-02-15T11:39:34Z" level=debug msg="authenticated as 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="authenticated as 21w7vemgh2mbw7vag653lutsy" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="dealer connection opened" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="received connection id: ZjhiNTNmOWQtMGExMi00MzU2LWE3MWItNTExMGZjNjFjMDE5K2RlYWxlcit0Y3A6Ly8wYWNhNDEyMy5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQkJFM0FGMDU0MzEyQzQxNTJDMUYzOEI2RDQ4MzJDRDc4QjRDOTU3NzhCMzg2RjE4NDMwOUZGNUZEQzNEMkI5Rg==" Feb 15 11:39:35 volumio go-librespot[752]: time="2024-02-15T11:39:35Z" level=debug msg="put connect state because NEW_DEVICE" Feb 15 11:39:37 volumio volumio[539]: info: Getting Spotify volume Feb 15 11:39:37 volumio volumio[539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Feb 15 11:39:37 volumio volumio[539]: info: Spotify volume: 100 Feb 15 11:39:37 volumio volumio[539]: info: CoreCommandRouter::volumioGetState Feb 15 11:39:37 volumio volumio[539]: info: CorePlayQueue::getTrack 0 Feb 15 11:39:37 volumio volumio[539]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 11:39:47 volumio volumio[539]: info: Discovery: Getting this device information Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::volumioGetState Feb 15 11:39:47 volumio volumio[539]: info: CorePlayQueue::getTrack 0 Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 11:39:47 volumio volumio[539]: verbose: New Socket.io Connection to 192.168.15.9:3000 from 192.168.15.7 UA: Dart/3.5 (dart:io) Total Clients: 4 Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::volumioGetState Feb 15 11:39:47 volumio volumio[539]: info: CorePlayQueue::getTrack 0 Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 15 11:39:47 volumio volumio[539]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 15 11:39:48 volumio volumio[539]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 11:39:48 volumio volumio[539]: Error: certificate is not yet valid Feb 15 11:39:48 volumio volumio[539]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Feb 15 11:39:48 volumio volumio[539]: at TLSSocket.emit (events.js:315:20) Feb 15 11:39:48 volumio volumio[539]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Feb 15 11:39:48 volumio volumio[539]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Feb 15 11:39:48 volumio volumio[539]: code: 'CERT_NOT_YET_VALID' Feb 15 11:39:48 volumio volumio[539]: } Feb 15 11:39:48 volumio volumio[539]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 11:39:49 volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-15 11:38 Feb 15 11:39:49 volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"