-- Logs begin at Sun 2024-10-13 12:02:59 +07, end at Sun 2024-10-13 12:16:22 +07. -- Oct 13 12:15:00 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:00 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 208. Oct 13 12:15:00 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:00 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:00 volumioasin.pham go-librespot[3109]: Librespot-go daemon starting... Oct 13 12:15:00 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:00 volumioasin.pham go-librespot[3109]: time="2024-10-13T12:15:00+07:00" level=info msg="generated new device id: 20778d0246a8b0f7cb24ef3c349fb198263b14ab" Oct 13 12:15:00 volumioasin.pham go-librespot[3109]: time="2024-10-13T12:15:00+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:00 volumioasin.pham go-librespot[3109]: time="2024-10-13T12:15:00+07:00" 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" Oct 13 12:15:00 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:00 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET Oct 13 12:15:00 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:02 volumioasin.pham ntpd[812]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 13 12:15:03 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:03 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:03 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:03 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 209. Oct 13 12:15:03 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:03 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:03 volumioasin.pham go-librespot[3116]: Librespot-go daemon starting... Oct 13 12:15:03 volumioasin.pham go-librespot[3116]: time="2024-10-13T12:15:03+07:00" level=info msg="generated new device id: 8a4181d9dd309129ec085b707cc46f923fdf412e" Oct 13 12:15:03 volumioasin.pham go-librespot[3116]: time="2024-10-13T12:15:03+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:03 volumioasin.pham go-librespot[3116]: time="2024-10-13T12:15:03+07:00" 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" Oct 13 12:15:03 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:03 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:06 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:06 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:06 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:06 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Oct 13 12:15:06 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:06 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:06 volumioasin.pham go-librespot[3124]: Librespot-go daemon starting... Oct 13 12:15:06 volumioasin.pham go-librespot[3124]: time="2024-10-13T12:15:06+07:00" level=info msg="generated new device id: 51e88ca7fa4e2808f5632e68b5b58addd05e60dc" Oct 13 12:15:06 volumioasin.pham go-librespot[3124]: time="2024-10-13T12:15:06+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:06 volumioasin.pham go-librespot[3124]: time="2024-10-13T12:15:06+07:00" 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" Oct 13 12:15:06 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:06 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:06 volumioasin.pham ntpd[812]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 13 12:15:09 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:09 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:09 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:09 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Oct 13 12:15:09 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:09 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:09 volumioasin.pham go-librespot[3131]: Librespot-go daemon starting... Oct 13 12:15:09 volumioasin.pham go-librespot[3131]: time="2024-10-13T12:15:09+07:00" level=info msg="generated new device id: eb3fec01a0f2b2d94a6a090fbe0ebd8c0cb1ccf8" Oct 13 12:15:09 volumioasin.pham go-librespot[3131]: time="2024-10-13T12:15:09+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:09 volumioasin.pham go-librespot[3131]: time="2024-10-13T12:15:09+07:00" 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" Oct 13 12:15:09 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:09 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:10 volumioasin.pham ntpd[812]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 13 12:15:12 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:12 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:12 volumioasin.pham ntpd[812]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 13 12:15:13 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:13 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Oct 13 12:15:13 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:13 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:13 volumioasin.pham go-librespot[3138]: Librespot-go daemon starting... Oct 13 12:15:13 volumioasin.pham go-librespot[3138]: time="2024-10-13T12:15:13+07:00" level=info msg="generated new device id: 02e91120b3324566cfe74d19e590080f4c08cd30" Oct 13 12:15:13 volumioasin.pham go-librespot[3138]: time="2024-10-13T12:15:13+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:13 volumioasin.pham go-librespot[3138]: time="2024-10-13T12:15:13+07:00" 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" Oct 13 12:15:13 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:13 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:15 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:15 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:16 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:16 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Oct 13 12:15:16 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:16 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:16 volumioasin.pham go-librespot[3145]: Librespot-go daemon starting... Oct 13 12:15:16 volumioasin.pham go-librespot[3145]: time="2024-10-13T12:15:16+07:00" level=info msg="generated new device id: aebfd33d04748e939426f63c43237595bafda17c" Oct 13 12:15:16 volumioasin.pham go-librespot[3145]: time="2024-10-13T12:15:16+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:16 volumioasin.pham go-librespot[3145]: time="2024-10-13T12:15:16+07:00" 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" Oct 13 12:15:16 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:16 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:18 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:18 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:19 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:19 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Oct 13 12:15:19 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:19 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:19 volumioasin.pham go-librespot[3152]: Librespot-go daemon starting... Oct 13 12:15:19 volumioasin.pham go-librespot[3152]: time="2024-10-13T12:15:19+07:00" level=info msg="generated new device id: c718a3cc04d15e107ca914c0e423e4a66f6b9298" Oct 13 12:15:19 volumioasin.pham go-librespot[3152]: time="2024-10-13T12:15:19+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:19 volumioasin.pham go-librespot[3152]: time="2024-10-13T12:15:19+07:00" 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" Oct 13 12:15:19 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:19 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:21 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:21 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:22 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:22 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Oct 13 12:15:22 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:22 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:22 volumioasin.pham go-librespot[3159]: Librespot-go daemon starting... Oct 13 12:15:22 volumioasin.pham go-librespot[3159]: time="2024-10-13T12:15:22+07:00" level=info msg="generated new device id: d076b4df68e5a0620d7a1b235fefbe2e8e1917ba" Oct 13 12:15:22 volumioasin.pham go-librespot[3159]: time="2024-10-13T12:15:22+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:22 volumioasin.pham go-librespot[3159]: time="2024-10-13T12:15:22+07:00" 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" Oct 13 12:15:22 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:22 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:24 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:24 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:26 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:26 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Oct 13 12:15:26 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:26 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:26 volumioasin.pham go-librespot[3166]: Librespot-go daemon starting... Oct 13 12:15:26 volumioasin.pham go-librespot[3166]: time="2024-10-13T12:15:26+07:00" level=info msg="generated new device id: cbe98b8484c7c739904dbf0dbfc0f02e4b8b169a" Oct 13 12:15:26 volumioasin.pham go-librespot[3166]: time="2024-10-13T12:15:26+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:26 volumioasin.pham go-librespot[3166]: time="2024-10-13T12:15:26+07:00" 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" Oct 13 12:15:26 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:26 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:27 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:27 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:29 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:29 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Oct 13 12:15:29 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:29 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:29 volumioasin.pham go-librespot[3173]: Librespot-go daemon starting... Oct 13 12:15:29 volumioasin.pham go-librespot[3173]: time="2024-10-13T12:15:29+07:00" level=info msg="generated new device id: fc4049a2e0903b6391ad0288c88ec72c844c8815" Oct 13 12:15:29 volumioasin.pham go-librespot[3173]: time="2024-10-13T12:15:29+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:29 volumioasin.pham go-librespot[3173]: time="2024-10-13T12:15:29+07:00" 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" Oct 13 12:15:29 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:29 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:30 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:30 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:32 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:32 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 218. Oct 13 12:15:32 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:32 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:32 volumioasin.pham go-librespot[3180]: Librespot-go daemon starting... Oct 13 12:15:32 volumioasin.pham go-librespot[3180]: time="2024-10-13T12:15:32+07:00" level=info msg="generated new device id: 29ff61c0f74b2585caa5634f66e779df47455a0c" Oct 13 12:15:32 volumioasin.pham go-librespot[3180]: time="2024-10-13T12:15:32+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:32 volumioasin.pham go-librespot[3180]: time="2024-10-13T12:15:32+07:00" 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" Oct 13 12:15:32 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:32 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:33 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:33 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:35 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:35 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219. Oct 13 12:15:35 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:35 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:35 volumioasin.pham go-librespot[3187]: Librespot-go daemon starting... Oct 13 12:15:35 volumioasin.pham go-librespot[3187]: time="2024-10-13T12:15:35+07:00" level=info msg="generated new device id: 2d4123747bb5d7960fb32c51dca6e488669172dc" Oct 13 12:15:35 volumioasin.pham go-librespot[3187]: time="2024-10-13T12:15:35+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:35 volumioasin.pham go-librespot[3187]: time="2024-10-13T12:15:35+07:00" 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" Oct 13 12:15:35 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:35 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:36 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:36 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:39 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:39 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 220. Oct 13 12:15:39 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:39 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:39 volumioasin.pham go-librespot[3194]: Librespot-go daemon starting... Oct 13 12:15:39 volumioasin.pham go-librespot[3194]: time="2024-10-13T12:15:39+07:00" level=info msg="generated new device id: 889fc25c4c1efa2dcbdb085c75b97e500549c3cf" Oct 13 12:15:39 volumioasin.pham go-librespot[3194]: time="2024-10-13T12:15:39+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:39 volumioasin.pham go-librespot[3194]: time="2024-10-13T12:15:39+07:00" 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" Oct 13 12:15:39 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:39 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:39 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:39 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:42 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:42 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:42 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:42 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 221. Oct 13 12:15:42 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:42 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:42 volumioasin.pham go-librespot[3201]: Librespot-go daemon starting... Oct 13 12:15:42 volumioasin.pham go-librespot[3201]: time="2024-10-13T12:15:42+07:00" level=info msg="generated new device id: 37f1bf51caa2bc31f9d09041e6e7c79979698811" Oct 13 12:15:42 volumioasin.pham go-librespot[3201]: time="2024-10-13T12:15:42+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:42 volumioasin.pham go-librespot[3201]: time="2024-10-13T12:15:42+07:00" 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" Oct 13 12:15:42 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:42 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:45 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:45 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:45 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:45 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 222. Oct 13 12:15:45 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:45 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:45 volumioasin.pham go-librespot[3208]: Librespot-go daemon starting... Oct 13 12:15:45 volumioasin.pham go-librespot[3208]: time="2024-10-13T12:15:45+07:00" level=info msg="generated new device id: 9e588bc34dd8589ec8653948c1184c5200715c09" Oct 13 12:15:45 volumioasin.pham go-librespot[3208]: time="2024-10-13T12:15:45+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:45 volumioasin.pham go-librespot[3208]: time="2024-10-13T12:15:45+07:00" 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" Oct 13 12:15:45 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:45 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:48 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:48 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:48 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:48 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 223. Oct 13 12:15:48 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:48 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:48 volumioasin.pham go-librespot[3215]: Librespot-go daemon starting... Oct 13 12:15:48 volumioasin.pham go-librespot[3215]: time="2024-10-13T12:15:48+07:00" level=info msg="generated new device id: 087617577aca1072179c1d3c29af085b9951fe87" Oct 13 12:15:48 volumioasin.pham go-librespot[3215]: time="2024-10-13T12:15:48+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:48 volumioasin.pham go-librespot[3215]: time="2024-10-13T12:15:48+07:00" 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" Oct 13 12:15:48 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:48 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:51 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:51 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:52 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:52 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 224. Oct 13 12:15:52 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:52 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:52 volumioasin.pham go-librespot[3222]: Librespot-go daemon starting... Oct 13 12:15:52 volumioasin.pham go-librespot[3222]: time="2024-10-13T12:15:52+07:00" level=info msg="generated new device id: 8ebac263ca26cc469c5dfa7e054cd082b0512643" Oct 13 12:15:52 volumioasin.pham go-librespot[3222]: time="2024-10-13T12:15:52+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:52 volumioasin.pham go-librespot[3222]: time="2024-10-13T12:15:52+07:00" 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" Oct 13 12:15:52 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:52 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:54 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:54 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:55 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:55 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 225. Oct 13 12:15:55 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:55 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:55 volumioasin.pham go-librespot[3229]: Librespot-go daemon starting... Oct 13 12:15:55 volumioasin.pham go-librespot[3229]: time="2024-10-13T12:15:55+07:00" level=info msg="generated new device id: 91b622dd15fe768c108928de39c09d63ec9d2d37" Oct 13 12:15:55 volumioasin.pham go-librespot[3229]: time="2024-10-13T12:15:55+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:55 volumioasin.pham go-librespot[3229]: time="2024-10-13T12:15:55+07:00" 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" Oct 13 12:15:55 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:55 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:15:57 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:15:57 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:15:58 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:15:58 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 226. Oct 13 12:15:58 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:15:58 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:15:58 volumioasin.pham go-librespot[3236]: Librespot-go daemon starting... Oct 13 12:15:58 volumioasin.pham go-librespot[3236]: time="2024-10-13T12:15:58+07:00" level=info msg="generated new device id: 3e7d27e98c7757f2c3a410c0c996bc449b50fd59" Oct 13 12:15:58 volumioasin.pham go-librespot[3236]: time="2024-10-13T12:15:58+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:15:58 volumioasin.pham go-librespot[3236]: time="2024-10-13T12:15:58+07:00" 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" Oct 13 12:15:58 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:15:58 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:16:00 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:00 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:16:01 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:16:01 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 227. Oct 13 12:16:01 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:16:01 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:16:01 volumioasin.pham go-librespot[3243]: Librespot-go daemon starting... Oct 13 12:16:01 volumioasin.pham go-librespot[3243]: time="2024-10-13T12:16:01+07:00" level=info msg="generated new device id: 7a5e8b4c80bd531454f65faddf3d3117455f96dd" Oct 13 12:16:01 volumioasin.pham go-librespot[3243]: time="2024-10-13T12:16:01+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:01 volumioasin.pham go-librespot[3243]: time="2024-10-13T12:16:01+07:00" 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" Oct 13 12:16:01 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:16:01 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:16:03 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:03 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:16:05 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:16:05 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 228. Oct 13 12:16:05 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:16:05 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:16:05 volumioasin.pham go-librespot[3250]: Librespot-go daemon starting... Oct 13 12:16:05 volumioasin.pham go-librespot[3250]: time="2024-10-13T12:16:05+07:00" level=info msg="generated new device id: f0c9c5b9b24f94a43264108a1f43949a7427af25" Oct 13 12:16:05 volumioasin.pham go-librespot[3250]: time="2024-10-13T12:16:05+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:05 volumioasin.pham go-librespot[3250]: time="2024-10-13T12:16:05+07:00" 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" Oct 13 12:16:05 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:16:05 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:16:06 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:06 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:16:07 volumioasin.pham ntpd[812]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 13 12:16:08 volumioasin.pham dhcpcd[553]: eth0: carrier acquired Oct 13 12:16:08 volumioasin.pham kernel: Generic FE-GE Realtek PHY r8169-0-200:00: Downshift occurred from negotiated speed 1Gbps to actual speed 100Mbps, check cabling! Oct 13 12:16:08 volumioasin.pham kernel: r8169 0000:02:00.0 eth0: Link is Up - 100Mbps/Full (downshifted) - flow control rx/tx Oct 13 12:16:08 volumioasin.pham dhcpcd[553]: eth0: IAID 0a:7c:8c:69 Oct 13 12:16:08 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:16:08 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 229. Oct 13 12:16:08 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:16:08 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:16:08 volumioasin.pham go-librespot[3259]: Librespot-go daemon starting... Oct 13 12:16:08 volumioasin.pham go-librespot[3259]: time="2024-10-13T12:16:08+07:00" level=info msg="generated new device id: 161260e37d8d16daa20a94425ad74e0d0b91778e" Oct 13 12:16:08 volumioasin.pham go-librespot[3259]: time="2024-10-13T12:16:08+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:08 volumioasin.pham go-librespot[3259]: time="2024-10-13T12:16:08+07:00" 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" Oct 13 12:16:08 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:16:08 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:16:08 volumioasin.pham dhcpcd[553]: eth0: soliciting an IPv6 router Oct 13 12:16:08 volumioasin.pham ifplugd(eth0)[802]: Link beat detected. Oct 13 12:16:08 volumioasin.pham ifplugd(eth0)[802]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 13 12:16:08 volumioasin.pham ifplugd(eth0)[802]: client: ifup: interface eth0 already configured Oct 13 12:16:08 volumioasin.pham ifplugd(eth0)[802]: Program executed successfully. Oct 13 12:16:09 volumioasin.pham dhcpcd[553]: eth0: soliciting a DHCP lease Oct 13 12:16:09 volumioasin.pham dhcpcd[553]: eth0: offered 192.168.1.20 from 192.168.1.1 Oct 13 12:16:09 volumioasin.pham dhcpcd[553]: eth0: probing address 192.168.1.20/24 Oct 13 12:16:09 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:09 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:16:11 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:16:11 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 230. Oct 13 12:16:11 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:16:11 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:16:11 volumioasin.pham go-librespot[3273]: Librespot-go daemon starting... Oct 13 12:16:11 volumioasin.pham go-librespot[3273]: time="2024-10-13T12:16:11+07:00" level=info msg="generated new device id: 338725175bb1c14c8b99acb40df52c8b48a1b570" Oct 13 12:16:11 volumioasin.pham go-librespot[3273]: time="2024-10-13T12:16:11+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:11 volumioasin.pham go-librespot[3273]: time="2024-10-13T12:16:11+07:00" 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" Oct 13 12:16:11 volumioasin.pham systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:16:11 volumioasin.pham systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 12:16:12 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:12 volumioasin.pham volumio[913]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 12:16:13 volumioasin.pham ntpd[812]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 13 12:16:13 volumioasin.pham dhcpcd[553]: eth0: leased 192.168.1.20 for 1800 seconds Oct 13 12:16:13 volumioasin.pham avahi-daemon[414]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.20. Oct 13 12:16:13 volumioasin.pham avahi-daemon[414]: New relevant interface eth0.IPv4 for mDNS. Oct 13 12:16:13 volumioasin.pham dhcpcd[553]: eth0: adding route to 192.168.1.0/24 Oct 13 12:16:13 volumioasin.pham dhcpcd[553]: eth0: adding default route via 192.168.1.1 Oct 13 12:16:13 volumioasin.pham avahi-daemon[414]: Registering new address record for 192.168.1.20 on eth0.IPv4. Oct 13 12:16:13 volumioasin.pham ntpd[812]: ntpd exiting on signal 15 (Terminated) Oct 13 12:16:13 volumioasin.pham systemd[1]: Stopping Network Time Service... Oct 13 12:16:13 volumioasin.pham systemd[1]: ntp.service: Succeeded. Oct 13 12:16:13 volumioasin.pham systemd[1]: Stopped Network Time Service. Oct 13 12:16:13 volumioasin.pham systemd[1]: Starting Network Time Service... Oct 13 12:16:13 volumioasin.pham ntpd[3314]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 13 12:16:13 volumioasin.pham ntpd[3314]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 13 12:16:13 volumioasin.pham systemd[1]: Started Network Time Service. Oct 13 12:16:13 volumioasin.pham ntpd[3320]: proto: precision = 0.060 usec (-24) Oct 13 12:16:13 volumioasin.pham ntpd[3320]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 13 12:16:13 volumioasin.pham ntpd[3320]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 13 12:16:13 volumioasin.pham ntpd[3320]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 656 days ago Oct 13 12:16:13 volumioasin.pham ntpd[3320]: Listen and drop on 0 v6wildcard [::]:123 Oct 13 12:16:13 volumioasin.pham ntpd[3320]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 13 12:16:13 volumioasin.pham ntpd[3320]: Listen normally on 2 lo 127.0.0.1:123 Oct 13 12:16:13 volumioasin.pham ntpd[3320]: Listen normally on 3 eth0 192.168.1.20:123 Oct 13 12:16:13 volumioasin.pham ntpd[3320]: Listening on routing socket on fd #20 for interface updates Oct 13 12:16:13 volumioasin.pham ntpd[3320]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 12:16:13 volumioasin.pham ntpd[3320]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 12:16:14 volumioasin.pham volumio[913]: info: Discovery: adding 9138c7ea-6307-45ad-b9fe-fb5c402816b4 Oct 13 12:16:14 volumioasin.pham volumio[913]: info: Discovery: Found device Volumio/@Asin.PHAM Oct 13 12:16:14 volumioasin.pham volumio[913]: info: CoreCommandRouter::volumioGetState Oct 13 12:16:14 volumioasin.pham volumio[913]: info: CorePlayQueue::getTrack 0 Oct 13 12:16:14 volumioasin.pham systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 13 12:16:14 volumioasin.pham systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 231. Oct 13 12:16:14 volumioasin.pham systemd[1]: Stopped go-librespot Daemon. Oct 13 12:16:14 volumioasin.pham systemd[1]: Started go-librespot Daemon. Oct 13 12:16:14 volumioasin.pham go-librespot[3324]: Librespot-go daemon starting... Oct 13 12:16:14 volumioasin.pham ntpd[3320]: Soliciting pool server 27.71.27.209 Oct 13 12:16:14 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:14+07:00" level=info msg="generated new device id: 662b263dd4d5c1f36f9f4be0f8b32f676a1c7635" Oct 13 12:16:14 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:14+07:00" level=debug msg="stored credentials found for 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:15 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:15+07:00" level=debug msg="obtained new client token: AADstztKFRJCc75y4TSTSyOf0qbktKYoE5xp0ug+s/CLyMk8+pKKBZqKgEwadXV5y4cOvhcgNYs3XPNO9unMhV10NvPCeGpAtRMHYJwNYCCuZ2tSYR2PpQIZ3zdfzlYlzIAuzTSIvYGTNOZPaQ1noS0AXxjz5VrZ12rI6sNq7GaTbde+5EN65AXVfNniLn5r1+b+WcgtDoT3hvbZ6B39l+Ipe52PuvU5zlz9rpyBvHS7gkTrhgWuB8Gg/Dn3Fjw=" Oct 13 12:16:15 volumioasin.pham volumio[913]: info: Initializing connection to go-librespot Websocket Oct 13 12:16:15 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:15+07:00" level=debug msg="new websocket client" Oct 13 12:16:15 volumioasin.pham volumio[913]: info: Connection to go-librespot Websocket established Oct 13 12:16:15 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 13 12:16:15 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:15+07:00" level=debug msg="completed keyexchange" Oct 13 12:16:15 volumioasin.pham ntpd[3320]: Soliciting pool server 103.130.217.41 Oct 13 12:16:15 volumioasin.pham ntpd[3320]: Soliciting pool server 103.184.124.23 Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="completed challenge" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="authenticated as 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="authenticated as 313yeapr5achzkw6xgxqktkuelre" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="dealer connection opened" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 13 12:16:16 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:16+07:00" level=debug msg="received connection id: MjdkN2Q2MWQtM2I5NS00ZDdkLTk0Y2EtOTNjZjk1YTdlNjIzK2RlYWxlcit0Y3A6Ly8wYWM5NDgzOC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArM0U1QkNBODQyMjRDQTU1M0U4RUU0MDVEN0ZBQjc5MzAwRDYwRUIyQzgzOTlDM0REMkNBMkVEQUFGQTE2QzY0NQ==" Oct 13 12:16:16 volumioasin.pham ntpd[3320]: Soliciting pool server 115.165.161.155 Oct 13 12:16:16 volumioasin.pham ntpd[3320]: Soliciting pool server 103.130.217.41 Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 12:16:16 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 13 12:16:17 volumioasin.pham go-librespot[3324]: time="2024-10-13T12:16:17+07:00" level=debug msg="put connect state because NEW_DEVICE" Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 12:16:17 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:16:17 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:16:17 volumioasin.pham volumio[913]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 12:16:17 volumioasin.pham volumio[913]: info: MyVolumio login type: Token Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 12:16:17 volumioasin.pham volumio[913]: info: Starting Streaming Service Transparent Proxy Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 12:16:17 volumioasin.pham volumio[913]: info: Streaming services startup Oct 13 12:16:17 volumioasin.pham volumio[913]: info: Starting Streaming Daemon Oct 13 12:16:17 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 12:16:17 volumioasin.pham volumio[913]: STREAMING PROXY: Starting server on port 3245 Oct 13 12:16:17 volumioasin.pham volumio[913]: Node JS runtime: 14 Oct 13 12:16:17 volumioasin.pham ntpd[3320]: Soliciting pool server 2a0c:b847:ffff:79::e53c Oct 13 12:16:17 volumioasin.pham sudo[3344]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:17 volumioasin.pham sudo[3344]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:17 volumioasin.pham sudo[3344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 12:16:17 volumioasin.pham sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:17 volumioasin.pham sudo[3344]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:17 volumioasin.pham volumio[913]: error: Cannot start Volumio Streaming Daemon Oct 13 12:16:17 volumioasin.pham volumio[913]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 12:16:17 volumioasin.pham volumio[913]: sudo: unable to resolve host volumioasin.pham: System error Oct 13 12:16:17 volumioasin.pham volumio[913]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 12:16:17 volumioasin.pham volumio[913]: error: MyVolumio Custom Token format not valid, refreshing it Oct 13 12:16:18 volumioasin.pham volumio[913]: info: Getting Spotify volume Oct 13 12:16:18 volumioasin.pham volumio[913]: info: Spotify volume: 100 Oct 13 12:16:18 volumioasin.pham volumio[913]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 13 12:16:18 volumioasin.pham volumio[913]: info: CoreCommandRouter::volumioGetState Oct 13 12:16:18 volumioasin.pham volumio[913]: info: CorePlayQueue::getTrack 0 Oct 13 12:16:18 volumioasin.pham volumio[913]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 13 12:16:18 volumioasin.pham volumio[913]: SPOTIFY: SPOTIFY VOLUME 100 Oct 13 12:16:18 volumioasin.pham volumio[913]: SPOTIFY: VOLUMIO VOLUME 100 Oct 13 12:16:18 volumioasin.pham volumio[913]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:16:19 volumioasin.pham volumio[913]: info: Discovery: Getting this device information Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::volumioGetState Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CorePlayQueue::getTrack 0 Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:16:19 volumioasin.pham volumio[913]: verbose: New Socket.io Connection to 192.168.1.20:3000 from 192.168.1.10 UA: Dart/3.4 (dart:io) Total Clients: 5 Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::volumioGetState Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CorePlayQueue::getTrack 0 Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 12:16:19 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 12:16:19 volumioasin.pham volumio[913]: info: MyVolumio login type: Token Oct 13 12:16:20 volumioasin.pham volumio[913]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MyVolumio token set successfully Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MYVOLUMIO: Adding device Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MYVOLUMIO: Evaluating Server Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MyVolumio Plan changed: premium Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Removing browser output: myVolumio user plan is not superstar Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Removing audio output: Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MYVOLUMIO: Adding device Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MYVOLUMIO: Evaluating Server Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Remote config written successfully Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Starting Tunnel 1 Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Starting Tunnel Connection Checker Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Setting Geolocation for MyVolumio to as1 Oct 13 12:16:21 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:16:21 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:16:21 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MYVolumio Device enabled Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MyVolumio status changed Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Streaming services startup Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Starting Streaming Daemon Oct 13 12:16:21 volumioasin.pham volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [1728796581464] Starting BluetoothController Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 13 12:16:21 volumioasin.pham sudo[3385]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3385]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 12:16:21 volumioasin.pham sudo[3385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3385]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 13 12:16:21 volumioasin.pham sudo[3389]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3389]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3391]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3391]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Oct 13 12:16:21 volumioasin.pham sudo[3389]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 13 12:16:21 volumioasin.pham sudo[3391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3389]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham sudo[3391]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham volumio[913]: sudo: unable to resolve host volumioasin.pham: System error Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MRS: MultiRoom plugin initialized Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MRS: STOPPING SNAPCLIENT Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MRS: Snap server stop Oct 13 12:16:21 volumioasin.pham volumio[913]: info: MRS: STOPPING volumioStreaming Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 13 12:16:21 volumioasin.pham sudo[3409]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3409]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 13 12:16:21 volumioasin.pham sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 13 12:16:21 volumioasin.pham sudo[3411]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3411]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 13 12:16:21 volumioasin.pham sudo[3411]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3413]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3413]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 13 12:16:21 volumioasin.pham sudo[3413]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3409]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham sudo[3411]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 13 12:16:21 volumioasin.pham sudo[3415]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:21 volumioasin.pham sudo[3415]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:21 volumioasin.pham sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 13 12:16:21 volumioasin.pham sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:16:21 volumioasin.pham sudo[3413]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 13 12:16:21 volumioasin.pham sudo[3415]: pam_unix(sudo:session): session closed for user root Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 13 12:16:21 volumioasin.pham volumio[913]: info: Preparing to generate the ALSA configuration file Oct 13 12:16:22 volumioasin.pham volumio[913]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 12:16:22 volumioasin.pham volumio[913]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 12:16:22 volumioasin.pham volumio[913]: info: Reading ALSA contributions from plugins. Oct 13 12:16:22 volumioasin.pham volumio[913]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Oct 13 12:16:22 volumioasin.pham volumio[913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:16:22 volumioasin.pham volumio[913]: Error: Unable to resolve or reject the same promise twice Oct 13 12:16:22 volumioasin.pham volumio[913]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 13 12:16:22 volumioasin.pham volumio[913]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 13 12:16:22 volumioasin.pham volumio[913]: at Socket.emit (events.js:412:35) Oct 13 12:16:22 volumioasin.pham volumio[913]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 13 12:16:22 volumioasin.pham volumio[913]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 13 12:16:22 volumioasin.pham volumio[913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:16:22 volumioasin.pham sudo[3429]: volumio : unable to resolve host volumioasin.pham Oct 13 12:16:22 volumioasin.pham sudo[3429]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 13 12:16:22 volumioasin.pham sudo[3429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 12:15 Oct 13 12:16:22 volumioasin.pham sudo[3429]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"