-- Logs begin at Sun 2024-09-08 12:22:47 CEST, end at Sun 2024-09-08 12:28:18 CEST. -- Sep 08 12:27:00 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:00 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:00 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:00 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Sep 08 12:27:00 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:00 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:00 kitchen-volumio go-librespot[2240]: Librespot-go daemon starting... Sep 08 12:27:00 kitchen-volumio go-librespot[2240]: time="2024-09-08T12:27:00+02:00" level=info msg="generated new device id: 614ed55cc32b3de223d02bda4b2405a547928c7a" Sep 08 12:27:00 kitchen-volumio go-librespot[2240]: time="2024-09-08T12:27:00+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:00 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:00 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:03 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:03 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:03 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:03 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Sep 08 12:27:03 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:03 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:03 kitchen-volumio go-librespot[2246]: Librespot-go daemon starting... Sep 08 12:27:03 kitchen-volumio go-librespot[2246]: time="2024-09-08T12:27:03+02:00" level=info msg="generated new device id: 7e810ebc487616006846df0bd97c0ebc77917bd5" Sep 08 12:27:03 kitchen-volumio go-librespot[2246]: time="2024-09-08T12:27:03+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:03 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:03 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:06 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:06 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:07 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:07 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Sep 08 12:27:07 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:07 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:07 kitchen-volumio go-librespot[2252]: Librespot-go daemon starting... Sep 08 12:27:07 kitchen-volumio go-librespot[2252]: time="2024-09-08T12:27:07+02:00" level=info msg="generated new device id: ab99f12b49023d701cbc456aefde25d5627c2fd8" Sep 08 12:27:07 kitchen-volumio go-librespot[2252]: time="2024-09-08T12:27:07+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:07 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:07 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:09 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:09 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:10 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:10 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Sep 08 12:27:10 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:10 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:10 kitchen-volumio go-librespot[2258]: Librespot-go daemon starting... Sep 08 12:27:10 kitchen-volumio go-librespot[2258]: time="2024-09-08T12:27:10+02:00" level=info msg="generated new device id: 05d5f36a899ecf9778a94ab789c20c9eaccea90b" Sep 08 12:27:10 kitchen-volumio go-librespot[2258]: time="2024-09-08T12:27:10+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:10 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:10 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:12 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:12 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:12 kitchen-volumio ntpd[828]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 08 12:27:13 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:13 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Sep 08 12:27:13 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:13 kitchen-volumio ntpd[828]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 08 12:27:13 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:13 kitchen-volumio go-librespot[2264]: Librespot-go daemon starting... Sep 08 12:27:13 kitchen-volumio go-librespot[2264]: time="2024-09-08T12:27:13+02:00" level=info msg="generated new device id: 60ec81654f29baca098de9e8326f6c34cf82c701" Sep 08 12:27:13 kitchen-volumio go-librespot[2264]: time="2024-09-08T12:27:13+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:13 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:13 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:15 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:15 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:16 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:16 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Sep 08 12:27:16 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:16 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:16 kitchen-volumio go-librespot[2270]: Librespot-go daemon starting... Sep 08 12:27:16 kitchen-volumio go-librespot[2270]: time="2024-09-08T12:27:16+02:00" level=info msg="generated new device id: 4224dbdcf3ea9089d04878437a3095c0885a6488" Sep 08 12:27:16 kitchen-volumio go-librespot[2270]: time="2024-09-08T12:27:16+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:16 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:16 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:18 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:18 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:18 kitchen-volumio ntpd[828]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 08 12:27:18 kitchen-volumio ntpd[828]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 08 12:27:20 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:20 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Sep 08 12:27:20 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:20 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:20 kitchen-volumio go-librespot[2277]: Librespot-go daemon starting... Sep 08 12:27:20 kitchen-volumio go-librespot[2277]: time="2024-09-08T12:27:20+02:00" level=info msg="generated new device id: 96be000834e8601cd27fbbcd6aa92b430cc11bba" Sep 08 12:27:20 kitchen-volumio go-librespot[2277]: time="2024-09-08T12:27:20+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:20 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:20 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:21 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:21 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:23 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:23 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Sep 08 12:27:23 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:23 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:23 kitchen-volumio go-librespot[2286]: Librespot-go daemon starting... Sep 08 12:27:23 kitchen-volumio go-librespot[2286]: time="2024-09-08T12:27:23+02:00" level=info msg="generated new device id: 579b095d5bc99a77e440cacf3bcdab78f3c4cae6" Sep 08 12:27:23 kitchen-volumio go-librespot[2286]: time="2024-09-08T12:27:23+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:23 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:23 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:24 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:24 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:26 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:26 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Sep 08 12:27:26 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:26 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:26 kitchen-volumio go-librespot[2292]: Librespot-go daemon starting... Sep 08 12:27:26 kitchen-volumio go-librespot[2292]: time="2024-09-08T12:27:26+02:00" level=info msg="generated new device id: 795f4e16a2d7ee3f5a050727290f25c136b61b5a" Sep 08 12:27:26 kitchen-volumio go-librespot[2292]: time="2024-09-08T12:27:26+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:26 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:26 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:27 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:27 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:29 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:29 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Sep 08 12:27:29 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:29 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:29 kitchen-volumio go-librespot[2298]: Librespot-go daemon starting... Sep 08 12:27:29 kitchen-volumio go-librespot[2298]: time="2024-09-08T12:27:29+02:00" level=info msg="generated new device id: 104dc3ed22be111cf1a2464e35bbae1a7f75a531" Sep 08 12:27:29 kitchen-volumio go-librespot[2298]: time="2024-09-08T12:27:29+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:29 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:29 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:30 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:30 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:33 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:33 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Sep 08 12:27:33 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:33 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:33 kitchen-volumio go-librespot[2304]: Librespot-go daemon starting... Sep 08 12:27:33 kitchen-volumio go-librespot[2304]: time="2024-09-08T12:27:33+02:00" level=info msg="generated new device id: 4aede878ab5640c12d26483e742a208a0bbbbb99" Sep 08 12:27:33 kitchen-volumio go-librespot[2304]: time="2024-09-08T12:27:33+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:33 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:33 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:33 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:33 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:36 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:36 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Sep 08 12:27:36 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:36 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:36 kitchen-volumio go-librespot[2310]: Librespot-go daemon starting... Sep 08 12:27:36 kitchen-volumio go-librespot[2310]: time="2024-09-08T12:27:36+02:00" level=info msg="generated new device id: 53324e7bf381f82f6acf1fb2f9b02ce9b51e81a2" Sep 08 12:27:36 kitchen-volumio go-librespot[2310]: time="2024-09-08T12:27:36+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:36 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:36 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:36 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:36 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:39 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:39 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:39 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:39 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Sep 08 12:27:39 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:39 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:39 kitchen-volumio go-librespot[2316]: Librespot-go daemon starting... Sep 08 12:27:39 kitchen-volumio go-librespot[2316]: time="2024-09-08T12:27:39+02:00" level=info msg="generated new device id: 9e042b59f62edb26bdb5e3edc1ac95ddf37a6c28" Sep 08 12:27:39 kitchen-volumio go-librespot[2316]: time="2024-09-08T12:27:39+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:39 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:39 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:42 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:42 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:42 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:42 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Sep 08 12:27:42 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:42 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:42 kitchen-volumio go-librespot[2322]: Librespot-go daemon starting... Sep 08 12:27:42 kitchen-volumio go-librespot[2322]: time="2024-09-08T12:27:42+02:00" level=info msg="generated new device id: 09ad32d558dc106053a737408510fd32a5b26e61" Sep 08 12:27:42 kitchen-volumio go-librespot[2322]: time="2024-09-08T12:27:42+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:42 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:42 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:45 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:45 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:46 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:46 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Sep 08 12:27:46 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:46 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:46 kitchen-volumio go-librespot[2328]: Librespot-go daemon starting... Sep 08 12:27:46 kitchen-volumio go-librespot[2328]: time="2024-09-08T12:27:46+02:00" level=info msg="generated new device id: 65cde8b31e3b09e9925280c4c810f11c59a154ff" Sep 08 12:27:46 kitchen-volumio go-librespot[2328]: time="2024-09-08T12:27:46+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:46 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:46 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: eth0: carrier acquired Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: eth0: IAID 4c:53:44:58 Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: eth0: adding address fe80::2541:3041:676e:35da Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: ipv6_addaddr1: Permission denied Sep 08 12:27:46 kitchen-volumio kernel: dm9601 4-1.4:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xFFFF Sep 08 12:27:46 kitchen-volumio kernel: dm9601 4-1.4:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xFFFF Sep 08 12:27:46 kitchen-volumio dhcpcd[720]: eth0: soliciting an IPv6 router Sep 08 12:27:47 kitchen-volumio dhcpcd[720]: eth0: rebinding lease of 192.168.50.18 Sep 08 12:27:47 kitchen-volumio dhcpcd[720]: eth0: probing address 192.168.50.18/24 Sep 08 12:27:48 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:48 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:49 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:49 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Sep 08 12:27:49 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:49 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:49 kitchen-volumio go-librespot[2335]: Librespot-go daemon starting... Sep 08 12:27:49 kitchen-volumio go-librespot[2335]: time="2024-09-08T12:27:49+02:00" level=info msg="generated new device id: 0b9fa806ee73064b8555e2a4105dc23a5b58d77f" Sep 08 12:27:49 kitchen-volumio go-librespot[2335]: time="2024-09-08T12:27:49+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:49 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:49 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:51 kitchen-volumio dhcpcd[720]: eth0: carrier lost Sep 08 12:27:51 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:51 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:52 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:52 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Sep 08 12:27:52 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:52 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:52 kitchen-volumio go-librespot[2400]: Librespot-go daemon starting... Sep 08 12:27:52 kitchen-volumio go-librespot[2400]: time="2024-09-08T12:27:52+02:00" level=info msg="generated new device id: b1035a7c3bc76ac0974d51031ae9945f43e337ef" Sep 08 12:27:52 kitchen-volumio go-librespot[2400]: time="2024-09-08T12:27:52+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:52 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:52 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:54 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:54 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:55 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:55 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Sep 08 12:27:55 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:55 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:55 kitchen-volumio go-librespot[2406]: Librespot-go daemon starting... Sep 08 12:27:55 kitchen-volumio go-librespot[2406]: time="2024-09-08T12:27:55+02:00" level=info msg="generated new device id: 1af1b58a0ef0851a203f806606031583c7fb3171" Sep 08 12:27:55 kitchen-volumio go-librespot[2406]: time="2024-09-08T12:27:55+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:55 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:55 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:27:57 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:27:57 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:27:59 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:27:59 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92. Sep 08 12:27:59 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:27:59 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:27:59 kitchen-volumio go-librespot[2412]: Librespot-go daemon starting... Sep 08 12:27:59 kitchen-volumio go-librespot[2412]: time="2024-09-08T12:27:59+02:00" level=info msg="generated new device id: f4bb88102a6211ab9c9ee5a0e8243df4166f383e" Sep 08 12:27:59 kitchen-volumio go-librespot[2412]: time="2024-09-08T12:27:59+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:27:59 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:27:59 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:28:00 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:28:00 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:28:02 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:28:02 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93. Sep 08 12:28:02 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:28:02 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:28:02 kitchen-volumio go-librespot[2418]: Librespot-go daemon starting... Sep 08 12:28:02 kitchen-volumio go-librespot[2418]: time="2024-09-08T12:28:02+02:00" level=info msg="generated new device id: e4a3198ceac9872e727fe67384e0310c49be9e25" Sep 08 12:28:02 kitchen-volumio go-librespot[2418]: time="2024-09-08T12:28:02+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:28:02 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:28:02 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:28:03 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:28:03 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:28:04 kitchen-volumio dhcpcd[720]: eth0: carrier acquired Sep 08 12:28:04 kitchen-volumio dhcpcd[720]: eth0: IAID 4c:53:44:58 Sep 08 12:28:04 kitchen-volumio dhcpcd[720]: eth0: rebinding lease of 192.168.50.18 Sep 08 12:28:04 kitchen-volumio kernel: dm9601 4-1.4:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xFFFF Sep 08 12:28:04 kitchen-volumio dhcpcd[720]: eth0: probing address 192.168.50.18/24 Sep 08 12:28:04 kitchen-volumio kernel: dm9601 4-1.4:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xFFFF Sep 08 12:28:04 kitchen-volumio dhcpcd[720]: eth0: soliciting an IPv6 router Sep 08 12:28:05 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:28:05 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94. Sep 08 12:28:05 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:28:05 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:28:05 kitchen-volumio go-librespot[2426]: Librespot-go daemon starting... Sep 08 12:28:05 kitchen-volumio go-librespot[2426]: time="2024-09-08T12:28:05+02:00" level=info msg="generated new device id: 0b9685636413ff29c059c5a9dfb5674ac89a78d4" Sep 08 12:28:05 kitchen-volumio go-librespot[2426]: time="2024-09-08T12:28:05+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:28:05 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:28:05 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:28:06 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:28:06 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:28:08 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:28:08 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Sep 08 12:28:08 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:28:08 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:28:08 kitchen-volumio go-librespot[2432]: Librespot-go daemon starting... Sep 08 12:28:08 kitchen-volumio go-librespot[2432]: time="2024-09-08T12:28:08+02:00" level=info msg="generated new device id: e93fca44e247fd170edde1e9146162c0baec2374" Sep 08 12:28:08 kitchen-volumio go-librespot[2432]: time="2024-09-08T12:28:08+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 08 12:28:08 kitchen-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 12:28:08 kitchen-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 12:28:09 kitchen-volumio dhcpcd[720]: eth0: leased 192.168.50.18 for 3600 seconds Sep 08 12:28:09 kitchen-volumio dhcpcd[720]: eth0: adding route to 192.168.50.0/24 Sep 08 12:28:09 kitchen-volumio dhcpcd[720]: eth0: adding default route via 192.168.50.1 Sep 08 12:28:09 kitchen-volumio avahi-daemon[586]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.18. Sep 08 12:28:09 kitchen-volumio ntpd[828]: ntpd exiting on signal 15 (Terminated) Sep 08 12:28:09 kitchen-volumio systemd[1]: Stopping Network Time Service... Sep 08 12:28:09 kitchen-volumio systemd[1]: ntp.service: Succeeded. Sep 08 12:28:09 kitchen-volumio systemd[1]: Stopped Network Time Service. Sep 08 12:28:09 kitchen-volumio avahi-daemon[586]: New relevant interface eth0.IPv4 for mDNS. Sep 08 12:28:09 kitchen-volumio avahi-daemon[586]: Registering new address record for 192.168.50.18 on eth0.IPv4. Sep 08 12:28:09 kitchen-volumio systemd[1]: Starting Network Time Service... Sep 08 12:28:09 kitchen-volumio ntpd[2474]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 08 12:28:09 kitchen-volumio ntpd[2474]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 08 12:28:09 kitchen-volumio systemd[1]: Started Network Time Service. Sep 08 12:28:09 kitchen-volumio ntpd[2480]: proto: precision = 0.107 usec (-23) Sep 08 12:28:09 kitchen-volumio ntpd[2480]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 08 12:28:09 kitchen-volumio ntpd[2480]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 08 12:28:09 kitchen-volumio ntpd[2480]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 621 days ago Sep 08 12:28:09 kitchen-volumio ntpd[2480]: Listen and drop on 0 v6wildcard [::]:123 Sep 08 12:28:09 kitchen-volumio ntpd[2480]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 08 12:28:09 kitchen-volumio ntpd[2480]: Listen normally on 2 lo 127.0.0.1:123 Sep 08 12:28:09 kitchen-volumio ntpd[2480]: Listen normally on 3 eth0 192.168.50.18:123 Sep 08 12:28:09 kitchen-volumio ntpd[2480]: Listening on routing socket on fd #20 for interface updates Sep 08 12:28:09 kitchen-volumio ntpd[2480]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 12:28:09 kitchen-volumio ntpd[2480]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 12:28:09 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:28:09 kitchen-volumio volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 12:28:10 kitchen-volumio ntpd[2480]: Soliciting pool server 217.175.196.134 Sep 08 12:28:11 kitchen-volumio kernel: usb 4-1.4: unexpected tiny rx frame Sep 08 12:28:11 kitchen-volumio ntpd[2480]: Soliciting pool server 151.236.30.71 Sep 08 12:28:11 kitchen-volumio ntpd[2480]: Soliciting pool server 194.177.151.10 Sep 08 12:28:12 kitchen-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 12:28:12 kitchen-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Sep 08 12:28:12 kitchen-volumio systemd[1]: Stopped go-librespot Daemon. Sep 08 12:28:12 kitchen-volumio systemd[1]: Started go-librespot Daemon. Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: Librespot-go daemon starting... Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=info msg="generated new device id: 5cad9a403518595cb1ed22abdcae2cc85fbb7805" Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=debug msg="fetched new accesspoints: [ap2-gew1.spotify.com:4070 ap2-gew1.spotify.com:443 ap2-gew1.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gue1.spotify.com:443 ap2-gae2.spotify.com:80]" Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=debug msg="zeroconf server listening on port 42597" Sep 08 12:28:12 kitchen-volumio ntpd[2480]: Soliciting pool server 178.251.64.52 Sep 08 12:28:12 kitchen-volumio ntpd[2480]: Soliciting pool server 37.252.189.117 Sep 08 12:28:12 kitchen-volumio ntpd[2480]: Soliciting pool server 91.206.8.36 Sep 08 12:28:12 kitchen-volumio volumio[849]: info: Initializing connection to go-librespot Websocket Sep 08 12:28:12 kitchen-volumio go-librespot[2483]: time="2024-09-08T12:28:12+02:00" level=debug msg="new websocket client" Sep 08 12:28:12 kitchen-volumio volumio[849]: info: Connection to go-librespot Websocket established Sep 08 12:28:13 kitchen-volumio ntpd[2480]: Soliciting pool server 162.159.200.1 Sep 08 12:28:13 kitchen-volumio ntpd[2480]: Soliciting pool server 91.206.8.70 Sep 08 12:28:13 kitchen-volumio ntpd[2480]: Soliciting pool server 83.215.130.11 Sep 08 12:28:13 kitchen-volumio ntpd[2480]: Soliciting pool server 46.102.157.67 Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin bluetooth to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin multiroom to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin metavolumio to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin cd_controller to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 08 12:28:13 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 08 12:28:14 kitchen-volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 12:28:14 kitchen-volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 12:28:14 kitchen-volumio volumio[849]: info: Starting MyVolumio Remote Streaming Endpoints Sep 08 12:28:14 kitchen-volumio volumio[849]: info: MyVolumio login type: Token Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 08 12:28:14 kitchen-volumio ntpd[2480]: Soliciting pool server 86.59.80.170 Sep 08 12:28:14 kitchen-volumio ntpd[2480]: Soliciting pool server 91.206.8.34 Sep 08 12:28:14 kitchen-volumio volumio[849]: info: Starting Streaming Service Transparent Proxy Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 08 12:28:14 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 08 12:28:14 kitchen-volumio volumio[849]: info: Streaming services startup Sep 08 12:28:14 kitchen-volumio volumio[849]: info: Starting Streaming Daemon Sep 08 12:28:14 kitchen-volumio sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 08 12:28:15 kitchen-volumio sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 12:28:15 kitchen-volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Sep 08 12:28:15 kitchen-volumio volumio[849]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 08 12:28:15 kitchen-volumio volumio[849]: error: Cannot start Volumio Streaming Daemon Sep 08 12:28:15 kitchen-volumio volumio[849]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 08 12:28:15 kitchen-volumio volumio[849]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 08 12:28:15 kitchen-volumio volumio[849]: info: CoreCommandRouter::volumioGetState Sep 08 12:28:15 kitchen-volumio volumio[849]: info: Listing playlists Sep 08 12:28:15 kitchen-volumio volumio[849]: info: Listing playlists Sep 08 12:28:15 kitchen-volumio ntpd[2480]: Soliciting pool server 185.119.117.217 Sep 08 12:28:15 kitchen-volumio ntpd[2480]: Soliciting pool server 162.159.200.123 Sep 08 12:28:15 kitchen-volumio volumio[849]: STREAMING PROXY: Starting server on port 3245 Sep 08 12:28:15 kitchen-volumio volumio[849]: Node JS runtime: 14 Sep 08 12:28:15 kitchen-volumio volumio[849]: info: Getting Spotify volume Sep 08 12:28:15 kitchen-volumio volumio[849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 08 12:28:15 kitchen-volumio volumio[849]: info: CoreCommandRouter::volumioGetState Sep 08 12:28:15 kitchen-volumio volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 08 12:28:15 kitchen-volumio volumio[849]: SPOTIFY: SPOTIFY VOLUME undefined Sep 08 12:28:15 kitchen-volumio volumio[849]: SPOTIFY: VOLUMIO VOLUME 50 Sep 08 12:28:15 kitchen-volumio volumio[849]: info: Aligning Spotify Volume to Volumio Volume Sep 08 12:28:15 kitchen-volumio volumio[849]: info: CoreCommandRouter::volumioGetState Sep 08 12:28:15 kitchen-volumio volumio[849]: info: Setting Spotify Volume from Volumio: 50 Sep 08 12:28:16 kitchen-volumio volumio[849]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 08 12:28:16 kitchen-volumio ntpd[2480]: Soliciting pool server 2a00:63c1:13:217::2 Sep 08 12:28:16 kitchen-volumio volumio[849]: info: CoreCommandRouter::volumioGetState Sep 08 12:28:17 kitchen-volumio volumio[849]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Sep 08 12:28:17 kitchen-volumio volumio[849]: info: Sending Spotify command with payload to local API: /player/volume Sep 08 12:28:17 kitchen-volumio volumio[849]: info: MyVolumio token set successfully Sep 08 12:28:17 kitchen-volumio volumio[849]: info: MYVOLUMIO: Adding device Sep 08 12:28:17 kitchen-volumio volumio[849]: info: MYVOLUMIO: Evaluating Server Sep 08 12:28:18 kitchen-volumio volumio[849]: info: MyVolumio status changed Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Streaming services startup Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Starting Streaming Daemon Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Removing browser output: myVolumio user plan is not superstar Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Removing audio output: Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Stoppping Tunnel 1 Sep 08 12:28:18 kitchen-volumio sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 08 12:28:18 kitchen-volumio sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 12:28:18 kitchen-volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Sep 08 12:28:18 kitchen-volumio volumio[849]: error: Cannot start Volumio Streaming Daemon Sep 08 12:28:18 kitchen-volumio volumio[849]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 08 12:28:18 kitchen-volumio volumio[849]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 08 12:28:18 kitchen-volumio sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 08 12:28:18 kitchen-volumio sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 12:28:18 kitchen-volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Sep 08 12:28:18 kitchen-volumio volumio[849]: info: Remote SSH Stopped Sep 08 12:28:18 kitchen-volumio ntpd[2480]: receive: Unexpected origin timestamp 0xea87fac2.562252cd does not match aorg 0000000000.00000000 from server@217.175.196.134 xmt 0xea87fac2.0ed81166 Sep 08 12:28:18 kitchen-volumio ntpd[2480]: receive: Unexpected origin timestamp 0xea87fac2.5621f89d does not match aorg 0000000000.00000000 from server@151.236.30.71 xmt 0xea87fac2.0f8dddb3 Sep 08 12:28:18 kitchen-volumio volumio[849]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Sep 08 12:28:18 kitchen-volumio volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 12:28:18 kitchen-volumio volumio[849]: Error: Unable to resolve or reject the same promise twice Sep 08 12:28:18 kitchen-volumio volumio[849]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 08 12:28:18 kitchen-volumio volumio[849]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Sep 08 12:28:18 kitchen-volumio volumio[849]: at Socket.emit (events.js:412:35) Sep 08 12:28:18 kitchen-volumio volumio[849]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 08 12:28:18 kitchen-volumio volumio[849]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 08 12:28:18 kitchen-volumio volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 12:28:18 kitchen-volumio ntpd[2480]: receive: Unexpected origin timestamp 0xea87fac2.561f76b6 does not match aorg 0000000000.00000000 from server@83.215.130.11 xmt 0xea87fac2.109c9fe2 Sep 08 12:28:18 kitchen-volumio sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 12:27 Sep 08 12:28:18 kitchen-volumio sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"