-- Logs begin at Fri 2024-11-22 17:13:49 EET, end at Wed 2024-11-27 19:03:11 EET. -- Nov 27 19:02:00 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:00 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:01 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:01 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 604. Nov 27 19:02:01 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:01 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:01 tomle go-librespot[7992]: Librespot-go daemon starting... Nov 27 19:02:01 tomle go-librespot[7992]: time="2024-11-27T19:02:01+02:00" level=info msg="generated new device id: 9d59d8e13e631d26a320dea3d48d91d23a30e71a" Nov 27 19:02:01 tomle go-librespot[7992]: time="2024-11-27T19:02:01+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:01 tomle go-librespot[7992]: time="2024-11-27T19:02:01+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:01 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:01 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:03 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:03 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:05 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:05 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 605. Nov 27 19:02:05 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:05 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:05 tomle go-librespot[7999]: Librespot-go daemon starting... Nov 27 19:02:05 tomle go-librespot[7999]: time="2024-11-27T19:02:05+02:00" level=info msg="generated new device id: 0deae9d2e632edd32762cdf6ff835a7d5d208249" Nov 27 19:02:05 tomle go-librespot[7999]: time="2024-11-27T19:02:05+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:05 tomle go-librespot[7999]: time="2024-11-27T19:02:05+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:05 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:05 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:06 tomle wpa_supplicant[877]: wlan0: Failed to initiate sched scan Nov 27 19:02:06 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:06 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:08 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:08 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 606. Nov 27 19:02:08 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:08 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:08 tomle go-librespot[8007]: Librespot-go daemon starting... Nov 27 19:02:08 tomle go-librespot[8007]: time="2024-11-27T19:02:08+02:00" level=info msg="generated new device id: 2f2c0a54efa401db8b61cad19a3859ebcf745ad0" Nov 27 19:02:08 tomle go-librespot[8007]: time="2024-11-27T19:02:08+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:08 tomle go-librespot[8007]: time="2024-11-27T19:02:08+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:08 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:08 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:09 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:09 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:11 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:11 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 607. Nov 27 19:02:11 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:11 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:11 tomle go-librespot[8014]: Librespot-go daemon starting... Nov 27 19:02:11 tomle go-librespot[8014]: time="2024-11-27T19:02:11+02:00" level=info msg="generated new device id: 08dd2bd14629525d2b516e95cb32257b83950a31" Nov 27 19:02:11 tomle go-librespot[8014]: time="2024-11-27T19:02:11+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:11 tomle go-librespot[8014]: time="2024-11-27T19:02:11+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:11 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:11 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:12 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:12 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:13 tomle wpa_supplicant[877]: wlan0: Failed to initiate sched scan Nov 27 19:02:14 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:14 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 608. Nov 27 19:02:14 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:14 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:14 tomle go-librespot[8021]: Librespot-go daemon starting... Nov 27 19:02:14 tomle go-librespot[8021]: time="2024-11-27T19:02:14+02:00" level=info msg="generated new device id: 111b49cdfd551d666b28bc4f77f8f0dee7a5b862" Nov 27 19:02:14 tomle go-librespot[8021]: time="2024-11-27T19:02:14+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:14 tomle go-librespot[8021]: time="2024-11-27T19:02:14+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:14 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:14 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:15 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:15 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:16 tomle kernel: CIFS: VFS: \\192.168.1.50 has not responded in 180 seconds. Reconnecting... Nov 27 19:02:18 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:18 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 609. Nov 27 19:02:18 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:18 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:18 tomle go-librespot[8029]: Librespot-go daemon starting... Nov 27 19:02:18 tomle go-librespot[8029]: time="2024-11-27T19:02:18+02:00" level=info msg="generated new device id: be62ea98875262a8ef6bdc81b855ba761b35db7c" Nov 27 19:02:18 tomle go-librespot[8029]: time="2024-11-27T19:02:18+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:18 tomle go-librespot[8029]: time="2024-11-27T19:02:18+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:18 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:18 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:18 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:18 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:21 tomle wpa_supplicant[877]: wlan0: Failed to initiate sched scan Nov 27 19:02:21 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:21 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:21 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:21 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 610. Nov 27 19:02:21 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:21 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:21 tomle go-librespot[8037]: Librespot-go daemon starting... Nov 27 19:02:21 tomle go-librespot[8037]: time="2024-11-27T19:02:21+02:00" level=info msg="generated new device id: 7426d8a1c4264b1a07e2c3df6512d620120f333c" Nov 27 19:02:21 tomle go-librespot[8037]: time="2024-11-27T19:02:21+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:21 tomle go-librespot[8037]: time="2024-11-27T19:02:21+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:21 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:21 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:24 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:24 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:24 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:24 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 611. Nov 27 19:02:24 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:24 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:24 tomle go-librespot[8045]: Librespot-go daemon starting... Nov 27 19:02:24 tomle go-librespot[8045]: time="2024-11-27T19:02:24+02:00" level=info msg="generated new device id: e244cf228f71bc40c4806037919eb16f78a332bd" Nov 27 19:02:24 tomle go-librespot[8045]: time="2024-11-27T19:02:24+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:24 tomle go-librespot[8045]: time="2024-11-27T19:02:24+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:24 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:24 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:26 tomle ntpd[781]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 27 19:02:27 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:27 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:27 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:27 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 612. Nov 27 19:02:27 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:27 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:27 tomle go-librespot[8052]: Librespot-go daemon starting... Nov 27 19:02:27 tomle go-librespot[8052]: time="2024-11-27T19:02:27+02:00" level=info msg="generated new device id: 32d31c8d423ee8ebd66b56d06871fbae7547227d" Nov 27 19:02:27 tomle go-librespot[8052]: time="2024-11-27T19:02:27+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:27 tomle go-librespot[8052]: time="2024-11-27T19:02:27+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:27 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:27 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:28 tomle wpa_supplicant[877]: wlan0: Trying to associate with SSID 'DNA-WIFI-AB8C' Nov 27 19:02:30 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:30 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:31 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:31 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 613. Nov 27 19:02:31 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:31 tomle ntpd[781]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 27 19:02:31 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:31 tomle go-librespot[8097]: Librespot-go daemon starting... Nov 27 19:02:31 tomle go-librespot[8097]: time="2024-11-27T19:02:31+02:00" level=info msg="generated new device id: e1b04ff76c7713b1ce52f3bd33a4d8df9cd82dc8" Nov 27 19:02:31 tomle go-librespot[8097]: time="2024-11-27T19:02:31+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:31 tomle go-librespot[8097]: time="2024-11-27T19:02:31+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:31 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:31 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: carrier acquired Nov 27 19:02:31 tomle wpa_supplicant[877]: wlan0: Associated with 2c:91:ab:94:07:f2 Nov 27 19:02:31 tomle wpa_supplicant[877]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:91:ab:94:07:f2 completed [id=0 id_str=] Nov 27 19:02:31 tomle wpa_supplicant[877]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 27 19:02:31 tomle wpa_supplicant[877]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FI Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: IAID 32:76:83:d4 Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: probing address 192.168.1.60/24 Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: soliciting an IPv6 router Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: carrier lost Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: carrier acquired Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: IAID 32:76:83:d4 Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: probing address 192.168.1.60/24 Nov 27 19:02:31 tomle dhcpcd[889]: wlan0: soliciting an IPv6 router Nov 27 19:02:33 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:33 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:34 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:34 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 614. Nov 27 19:02:34 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:34 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:34 tomle go-librespot[8126]: Librespot-go daemon starting... Nov 27 19:02:34 tomle go-librespot[8126]: time="2024-11-27T19:02:34+02:00" level=info msg="generated new device id: f70349aa59dc315df885ad966546064d24a9868e" Nov 27 19:02:34 tomle go-librespot[8126]: time="2024-11-27T19:02:34+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:34 tomle go-librespot[8126]: time="2024-11-27T19:02:34+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:02:34 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:02:34 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:02:36 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:36 tomle volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 27 19:02:36 tomle dhcpcd[889]: wlan0: using static address 192.168.1.60/24 Nov 27 19:02:36 tomle avahi-daemon[639]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.60. Nov 27 19:02:36 tomle avahi-daemon[639]: New relevant interface wlan0.IPv4 for mDNS. Nov 27 19:02:36 tomle avahi-daemon[639]: Registering new address record for 192.168.1.60 on wlan0.IPv4. Nov 27 19:02:36 tomle dhcpcd[889]: wlan0: adding route to 192.168.1.0/24 Nov 27 19:02:36 tomle dhcpcd[889]: wlan0: adding default route via 192.168.1.1 Nov 27 19:02:36 tomle volumio[978]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Nov 27 19:02:36 tomle volumio[978]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Nov 27 19:02:36 tomle volumio[978]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Nov 27 19:02:36 tomle volumio[978]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Nov 27 19:02:37 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:02:37 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 615. Nov 27 19:02:37 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:02:37 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:02:37 tomle go-librespot[8158]: Librespot-go daemon starting... Nov 27 19:02:37 tomle go-librespot[8158]: time="2024-11-27T19:02:37+02:00" level=info msg="generated new device id: b2bbaf93ea22131d0a5ec616be2b85734aded697" Nov 27 19:02:37 tomle go-librespot[8158]: time="2024-11-27T19:02:37+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:02:38 tomle ntpd[781]: Listen normally on 11 wlan0 192.168.1.60:123 Nov 27 19:02:38 tomle ntpd[781]: new interface(s) found: waking up resolver Nov 27 19:02:39 tomle volumio[978]: info: Initializing connection to go-librespot Websocket Nov 27 19:02:40 tomle volumio[978]: verbose: New Socket.io Connection to 192.168.1.60 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetQueue Nov 27 19:02:40 tomle volumio[978]: info: CoreStateMachine::getQueue Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getQueue Nov 27 19:02:40 tomle volumio[978]: info: Listing playlists Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 27 19:02:40 tomle volumio[978]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 27 19:02:40 tomle volumio[978]: info: Received Get System Info Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 19:02:40 tomle volumio[978]: info: Discovery: Getting this device information Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 27 19:02:40 tomle volumio[978]: info: Received Get System Info Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 19:02:40 tomle volumio[978]: info: Discovery: Getting this device information Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 27 19:02:40 tomle volumio[978]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 27 19:02:40 tomle volumio[978]: info: Received Get System Version Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 19:02:40 tomle volumio[978]: info: Received Get System Info Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 19:02:40 tomle volumio[978]: info: Discovery: Getting this device information Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetQueue Nov 27 19:02:40 tomle volumio[978]: info: CoreStateMachine::getQueue Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getQueue Nov 27 19:02:40 tomle volumio[978]: info: Listing playlists Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 27 19:02:40 tomle volumio[978]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 27 19:02:40 tomle volumio[978]: info: Received Get System Info Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 19:02:40 tomle volumio[978]: info: Discovery: Getting this device information Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::volumioGetState Nov 27 19:02:40 tomle volumio[978]: info: CorePlayQueue::getTrack 39 Nov 27 19:02:40 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 27 19:02:42 tomle volumio[978]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 27 19:03:04 tomle volumio[978]: info: MRS: Found cast device: SHIELD-Android-TV-8dc58dfb46aac533e83c75fd07fa8fa8 Nov 27 19:03:04 tomle volumio[978]: info: MRS: Found cast device: Google-Nest-Hub-7c10c3cbe8548ae5614c3a1f698f9450 Nov 27 19:03:05 tomle go-librespot[8158]: time="2024-11-27T19:03:05+02:00" level=fatal msg="failed running with username and spotify token" 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" Nov 27 19:03:05 tomle systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 27 19:03:05 tomle systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 27 19:03:08 tomle systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 27 19:03:08 tomle systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 616. Nov 27 19:03:08 tomle systemd[1]: Stopped go-librespot Daemon. Nov 27 19:03:08 tomle systemd[1]: Started go-librespot Daemon. Nov 27 19:03:08 tomle go-librespot[8173]: Librespot-go daemon starting... Nov 27 19:03:08 tomle go-librespot[8173]: time="2024-11-27T19:03:08+02:00" level=info msg="generated new device id: 0985b9975415e41a78d6e59d94646a17ae8effa2" Nov 27 19:03:08 tomle go-librespot[8173]: time="2024-11-27T19:03:08+02:00" level=debug msg="stored credentials found for hellf" Nov 27 19:03:10 tomle volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 19:03:10 tomle volumio[978]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Nov 27 19:03:10 tomle volumio[978]: code: 'auth/network-request-failed', Nov 27 19:03:10 tomle volumio[978]: a: null Nov 27 19:03:10 tomle volumio[978]: } Nov 27 19:03:10 tomle volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 19:03:11 tomle sudo[8191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 19:02 Nov 27 19:03:11 tomle sudo[8191]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"