-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-06-29 13:56:19 CEST. -- Jun 29 13:55:00 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:00 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:00 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:00 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:01 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:01 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:01 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:01 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:02 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:02 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:02 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:02 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:02 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:02 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:02 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:02 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Jun 29 13:55:02 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:02 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:02 teac-volumio go-librespot[1785]: Librespot-go daemon starting... Jun 29 13:55:02 teac-volumio go-librespot[1785]: time="2024-06-29T13:55:02+02:00" level=info msg="generated new device id: 8bf12c6435bc85eb0b9558a1b916591092adf568" Jun 29 13:55:02 teac-volumio go-librespot[1785]: time="2024-06-29T13:55:02+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:02 teac-volumio go-librespot[1785]: time="2024-06-29T13:55:02+02: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" Jun 29 13:55:02 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:02 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:03 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:03 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:03 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:03 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:04 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:04 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:04 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:04 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:05 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:05 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:05 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:05 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:05 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:05 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:06 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:06 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Jun 29 13:55:06 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:06 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:06 teac-volumio go-librespot[1792]: Librespot-go daemon starting... Jun 29 13:55:06 teac-volumio go-librespot[1792]: time="2024-06-29T13:55:06+02:00" level=info msg="generated new device id: 0791917cbd86d0be09bae0594a4e4ab470ec8285" Jun 29 13:55:06 teac-volumio go-librespot[1792]: time="2024-06-29T13:55:06+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:06 teac-volumio go-librespot[1792]: time="2024-06-29T13:55:06+02: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" Jun 29 13:55:06 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:06 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:06 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:06 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:06 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:06 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:07 teac-volumio ntpd[671]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 29 13:55:07 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:07 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:07 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:07 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:08 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:08 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:08 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:08 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:08 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:08 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:09 teac-volumio ntpd[671]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 29 13:55:09 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:09 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:09 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:09 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:09 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:09 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Jun 29 13:55:09 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:09 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:09 teac-volumio go-librespot[1799]: Librespot-go daemon starting... Jun 29 13:55:09 teac-volumio go-librespot[1799]: time="2024-06-29T13:55:09+02:00" level=info msg="generated new device id: 1af7cca187f696db8450625c1473ee881c3119a8" Jun 29 13:55:09 teac-volumio go-librespot[1799]: time="2024-06-29T13:55:09+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:09 teac-volumio go-librespot[1799]: time="2024-06-29T13:55:09+02: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" Jun 29 13:55:09 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:09 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:10 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:10 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:10 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:10 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:11 teac-volumio ntpd[671]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 29 13:55:11 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:11 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:11 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:11 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:11 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:11 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:12 teac-volumio ntpd[671]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 29 13:55:12 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:12 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:12 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:12 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:12 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:12 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Jun 29 13:55:12 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:12 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:12 teac-volumio go-librespot[1807]: Librespot-go daemon starting... Jun 29 13:55:12 teac-volumio go-librespot[1807]: time="2024-06-29T13:55:12+02:00" level=info msg="generated new device id: f83d0a9974a392870b1841f06c08d6e9a844fb0f" Jun 29 13:55:12 teac-volumio go-librespot[1807]: time="2024-06-29T13:55:12+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:12 teac-volumio go-librespot[1807]: time="2024-06-29T13:55:12+02: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" Jun 29 13:55:12 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:12 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:13 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:13 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:13 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:13 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:14 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:14 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:14 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:14 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:14 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:14 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:15 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:15 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:15 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:15 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:15 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:15 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Jun 29 13:55:15 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:15 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:15 teac-volumio go-librespot[1814]: Librespot-go daemon starting... Jun 29 13:55:15 teac-volumio go-librespot[1814]: time="2024-06-29T13:55:15+02:00" level=info msg="generated new device id: 0c58896fea1ddd3ef0cea748bfc8433cf5ca9587" Jun 29 13:55:15 teac-volumio go-librespot[1814]: time="2024-06-29T13:55:15+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:16 teac-volumio go-librespot[1814]: time="2024-06-29T13:55:16+02: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" Jun 29 13:55:16 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:16 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:16 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:16 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:16 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:16 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:17 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:17 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:17 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:17 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:17 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:17 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:18 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:18 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:18 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:18 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:19 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:19 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Jun 29 13:55:19 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:19 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:19 teac-volumio go-librespot[1823]: Librespot-go daemon starting... Jun 29 13:55:19 teac-volumio go-librespot[1823]: time="2024-06-29T13:55:19+02:00" level=info msg="generated new device id: 7b4c5f273f7f84d9685c302bf34ad9fa4f0a38dd" Jun 29 13:55:19 teac-volumio go-librespot[1823]: time="2024-06-29T13:55:19+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:19 teac-volumio go-librespot[1823]: time="2024-06-29T13:55:19+02: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" Jun 29 13:55:19 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:19 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:19 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:19 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:19 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:19 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:20 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:20 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:20 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:20 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:20 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:20 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:21 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:21 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:21 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:21 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:22 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:22 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:22 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:22 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:22 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:22 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Jun 29 13:55:22 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:22 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:22 teac-volumio go-librespot[1830]: Librespot-go daemon starting... Jun 29 13:55:22 teac-volumio go-librespot[1830]: time="2024-06-29T13:55:22+02:00" level=info msg="generated new device id: 332cb8de63d670758472bf89eed53d44ec63a213" Jun 29 13:55:22 teac-volumio go-librespot[1830]: time="2024-06-29T13:55:22+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:22 teac-volumio go-librespot[1830]: time="2024-06-29T13:55:22+02: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" Jun 29 13:55:22 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:22 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:23 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:23 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:23 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:23 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:23 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:23 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:24 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:24 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:24 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:24 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:25 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:25 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:25 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:25 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:25 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:25 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Jun 29 13:55:25 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:25 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:25 teac-volumio go-librespot[1837]: Librespot-go daemon starting... Jun 29 13:55:25 teac-volumio go-librespot[1837]: time="2024-06-29T13:55:25+02:00" level=info msg="generated new device id: cc02e3947bf4cf344c505b707444b8561ab485b2" Jun 29 13:55:25 teac-volumio go-librespot[1837]: time="2024-06-29T13:55:25+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:25 teac-volumio go-librespot[1837]: time="2024-06-29T13:55:25+02: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" Jun 29 13:55:25 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:25 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:26 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:26 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:26 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:26 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:26 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:26 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:27 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:27 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:27 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:27 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:28 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:28 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:28 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:28 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:28 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:28 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jun 29 13:55:28 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:28 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:28 teac-volumio go-librespot[1844]: Librespot-go daemon starting... Jun 29 13:55:28 teac-volumio go-librespot[1844]: time="2024-06-29T13:55:28+02:00" level=info msg="generated new device id: 1c0fc89eed5227c05dd744a5d6a1fcdab92f881b" Jun 29 13:55:28 teac-volumio go-librespot[1844]: time="2024-06-29T13:55:28+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:28 teac-volumio go-librespot[1844]: time="2024-06-29T13:55:28+02: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" Jun 29 13:55:28 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:28 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:29 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:29 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:29 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:29 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:29 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:29 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:30 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:30 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:30 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:30 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:31 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:31 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:31 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:31 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:32 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:32 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jun 29 13:55:32 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:32 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:32 teac-volumio go-librespot[1852]: Librespot-go daemon starting... Jun 29 13:55:32 teac-volumio go-librespot[1852]: time="2024-06-29T13:55:32+02:00" level=info msg="generated new device id: cf589b8147df51c6e5a0bdf16339dd26c4fc6e90" Jun 29 13:55:32 teac-volumio go-librespot[1852]: time="2024-06-29T13:55:32+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:32 teac-volumio go-librespot[1852]: time="2024-06-29T13:55:32+02: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" Jun 29 13:55:32 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:32 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:32 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:32 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:32 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:32 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:32 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:32 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:33 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:33 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:33 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:33 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:34 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:34 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:34 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:34 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:35 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:35 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:35 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:35 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:35 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:35 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jun 29 13:55:35 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:35 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:35 teac-volumio go-librespot[1859]: Librespot-go daemon starting... Jun 29 13:55:35 teac-volumio go-librespot[1859]: time="2024-06-29T13:55:35+02:00" level=info msg="generated new device id: a3a955b75b4730371495d02a7179fc276bc8bef0" Jun 29 13:55:35 teac-volumio go-librespot[1859]: time="2024-06-29T13:55:35+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:35 teac-volumio go-librespot[1859]: time="2024-06-29T13:55:35+02: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" Jun 29 13:55:35 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:35 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:35 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:35 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:36 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:36 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:36 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:36 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:37 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:37 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:37 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:37 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:38 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:38 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:38 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:38 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:38 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:38 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Jun 29 13:55:38 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:38 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:38 teac-volumio go-librespot[1866]: Librespot-go daemon starting... Jun 29 13:55:38 teac-volumio go-librespot[1866]: time="2024-06-29T13:55:38+02:00" level=info msg="generated new device id: 5ea837dbf7d3358e4702b285887f5d558231e30c" Jun 29 13:55:38 teac-volumio go-librespot[1866]: time="2024-06-29T13:55:38+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:38 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:38 teac-volumio go-librespot[1866]: time="2024-06-29T13:55:38+02: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" Jun 29 13:55:38 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET Jun 29 13:55:38 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:38 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:39 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:39 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:39 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:39 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:40 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:40 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:40 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:40 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:41 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:41 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:41 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:41 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:41 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:41 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:41 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:41 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Jun 29 13:55:41 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:41 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:41 teac-volumio go-librespot[1874]: Librespot-go daemon starting... Jun 29 13:55:41 teac-volumio go-librespot[1874]: time="2024-06-29T13:55:41+02:00" level=info msg="generated new device id: 061380d05dfd617fa88c69ebda42e103ed26c3aa" Jun 29 13:55:41 teac-volumio go-librespot[1874]: time="2024-06-29T13:55:41+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:42 teac-volumio go-librespot[1874]: time="2024-06-29T13:55:42+02: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" Jun 29 13:55:42 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:42 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:42 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:42 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:42 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:42 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:43 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:43 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:43 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:43 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:44 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:44 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:44 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:44 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:44 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:44 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:45 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:45 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Jun 29 13:55:45 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:45 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:45 teac-volumio go-librespot[1881]: Librespot-go daemon starting... Jun 29 13:55:45 teac-volumio go-librespot[1881]: time="2024-06-29T13:55:45+02:00" level=info msg="generated new device id: eb995ca8e5f6b417a22bebed3bfd4251992c586f" Jun 29 13:55:45 teac-volumio go-librespot[1881]: time="2024-06-29T13:55:45+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:45 teac-volumio go-librespot[1881]: time="2024-06-29T13:55:45+02: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" Jun 29 13:55:45 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:45 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:45 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:45 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:45 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:45 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:46 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:46 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:46 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:46 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:47 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:47 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:47 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:47 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:47 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:47 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:48 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:48 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:48 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:48 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:48 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:48 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Jun 29 13:55:48 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:48 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:48 teac-volumio go-librespot[1889]: Librespot-go daemon starting... Jun 29 13:55:48 teac-volumio go-librespot[1889]: time="2024-06-29T13:55:48+02:00" level=info msg="generated new device id: 799fb411fac8d70ba8a5f43c1978a910257aaaaf" Jun 29 13:55:48 teac-volumio go-librespot[1889]: time="2024-06-29T13:55:48+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:48 teac-volumio go-librespot[1889]: time="2024-06-29T13:55:48+02: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" Jun 29 13:55:48 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:48 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:49 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:49 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:49 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:49 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:50 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:50 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:50 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:50 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:50 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:50 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:51 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:51 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:51 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:51 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:51 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:51 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Jun 29 13:55:51 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:51 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:51 teac-volumio go-librespot[1897]: Librespot-go daemon starting... Jun 29 13:55:51 teac-volumio go-librespot[1897]: time="2024-06-29T13:55:51+02:00" level=info msg="generated new device id: 22ae9096e1c42c35cf6ef134273d7b03ecbed9de" Jun 29 13:55:51 teac-volumio go-librespot[1897]: time="2024-06-29T13:55:51+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:51 teac-volumio go-librespot[1897]: time="2024-06-29T13:55:51+02: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" Jun 29 13:55:51 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:51 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:52 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:52 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:52 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:52 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:53 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:53 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:53 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:53 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:53 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:53 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:54 teac-volumio volumiologrotate[542]: ls: cannot access '/var/log/samba/log.wb-TEAC': No such file or directory Jun 29 13:55:54 teac-volumio volumiologrotate[542]: ls: cannot access 'VOLUMIO': No such file or directory Jun 29 13:55:54 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:54 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:54 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:54 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:54 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:54 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Jun 29 13:55:54 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:54 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:54 teac-volumio go-librespot[1948]: Librespot-go daemon starting... Jun 29 13:55:54 teac-volumio go-librespot[1948]: time="2024-06-29T13:55:54+02:00" level=info msg="generated new device id: ccb62738b393af96cfa0d1ac4c72dd08174ba74a" Jun 29 13:55:54 teac-volumio go-librespot[1948]: time="2024-06-29T13:55:54+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:54 teac-volumio go-librespot[1948]: time="2024-06-29T13:55:54+02: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" Jun 29 13:55:54 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:55 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:55 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:55 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:55 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:55 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:56 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:56 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:56 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:56 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:56 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:56 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:55:57 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:57 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:57 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:57 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:58 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:55:58 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Jun 29 13:55:58 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:55:58 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:55:58 teac-volumio go-librespot[1955]: Librespot-go daemon starting... Jun 29 13:55:58 teac-volumio go-librespot[1955]: time="2024-06-29T13:55:58+02:00" level=info msg="generated new device id: 411cdece2931246c6f4d4730ce1be9f1e085a03a" Jun 29 13:55:58 teac-volumio go-librespot[1955]: time="2024-06-29T13:55:58+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:55:58 teac-volumio go-librespot[1955]: time="2024-06-29T13:55:58+02: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" Jun 29 13:55:58 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:55:58 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:55:58 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:58 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:58 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:58 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:59 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:59 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:59 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:55:59 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:55:59 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:55:59 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:56:00 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:00 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:00 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:00 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:01 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:01 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:01 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:01 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:01 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:56:01 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Jun 29 13:56:01 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:56:01 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:56:01 teac-volumio go-librespot[1963]: Librespot-go daemon starting... Jun 29 13:56:01 teac-volumio go-librespot[1963]: time="2024-06-29T13:56:01+02:00" level=info msg="generated new device id: a0350429fc064993d5ab1c8357bd14a7d3e32d0e" Jun 29 13:56:01 teac-volumio go-librespot[1963]: time="2024-06-29T13:56:01+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:56:01 teac-volumio go-librespot[1963]: time="2024-06-29T13:56:01+02: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" Jun 29 13:56:01 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:56:01 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:56:02 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:02 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:02 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:02 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:02 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:56:02 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:56:03 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:03 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:03 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:03 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:04 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:04 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:04 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:04 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:04 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:56:04 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Jun 29 13:56:04 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:56:04 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:56:04 teac-volumio go-librespot[1971]: Librespot-go daemon starting... Jun 29 13:56:04 teac-volumio go-librespot[1971]: time="2024-06-29T13:56:04+02:00" level=info msg="generated new device id: a0a20b390f3647568ed585c3d33a8244860de0f6" Jun 29 13:56:04 teac-volumio go-librespot[1971]: time="2024-06-29T13:56:04+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:56:04 teac-volumio go-librespot[1971]: time="2024-06-29T13:56:04+02: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" Jun 29 13:56:04 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:56:04 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:56:05 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:05 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:05 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:05 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:05 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:56:05 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:56:06 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:06 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:06 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:06 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:06 teac-volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jun 29 13:56:07 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:07 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:07 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:07 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: Link beat detected. Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 29 13:56:07 teac-volumio dhcpcd[1999]: DUID 00:01:00:01:2d:5e:64:94:dc:a6:32:60:73:17 Jun 29 13:56:07 teac-volumio dhcpcd[1999]: eth0: IAID 32:60:73:17 Jun 29 13:56:07 teac-volumio dhcpcd[1999]: eth0: adding address fe80::dea6:32ff:fe60:7317 Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: client: DUID 00:01:00:01:2d:5e:64:94:dc:a6:32:60:73:17 Jun 29 13:56:07 teac-volumio dhcpcd[1999]: ipv6_addaddr1: Permission denied Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: client: eth0: IAID 32:60:73:17 Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: client: eth0: adding address fe80::dea6:32ff:fe60:7317 Jun 29 13:56:07 teac-volumio ifplugd(eth0)[674]: client: ipv6_addaddr1: Permission denied Jun 29 13:56:07 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:56:07 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Jun 29 13:56:07 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:56:07 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:56:07 teac-volumio go-librespot[2002]: Librespot-go daemon starting... Jun 29 13:56:07 teac-volumio go-librespot[2002]: time="2024-06-29T13:56:07+02:00" level=info msg="generated new device id: 2e91db475db9096b2331811fa6d3946951b402ff" Jun 29 13:56:07 teac-volumio go-librespot[2002]: time="2024-06-29T13:56:07+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:56:07 teac-volumio go-librespot[2002]: time="2024-06-29T13:56:07+02: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" Jun 29 13:56:08 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:56:08 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:56:08 teac-volumio dhcpcd[1999]: eth0: soliciting an IPv6 router Jun 29 13:56:08 teac-volumio ifplugd(eth0)[674]: client: eth0: soliciting an IPv6 router Jun 29 13:56:08 teac-volumio dhcpcd[1999]: eth0: soliciting a DHCP lease Jun 29 13:56:08 teac-volumio ifplugd(eth0)[674]: client: eth0: soliciting a DHCP lease Jun 29 13:56:08 teac-volumio dhcpcd[1999]: eth0: offered 10.10.50.122 from 10.10.50.1 Jun 29 13:56:08 teac-volumio ifplugd(eth0)[674]: client: eth0: offered 10.10.50.122 from 10.10.50.1 Jun 29 13:56:08 teac-volumio dhcpcd[1999]: eth0: probing address 10.10.50.122/24 Jun 29 13:56:08 teac-volumio ifplugd(eth0)[674]: client: eth0: probing address 10.10.50.122/24 Jun 29 13:56:08 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:08 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:08 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:08 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:08 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:56:08 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:56:09 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:09 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:09 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:09 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:10 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:10 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:10 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:10 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:11 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:56:11 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Jun 29 13:56:11 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:56:11 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:56:11 teac-volumio go-librespot[2010]: Librespot-go daemon starting... Jun 29 13:56:11 teac-volumio go-librespot[2010]: time="2024-06-29T13:56:11+02:00" level=info msg="generated new device id: 028b1dd93d0d44bb396b863d47d3d5bc3ee86611" Jun 29 13:56:11 teac-volumio go-librespot[2010]: time="2024-06-29T13:56:11+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:56:11 teac-volumio go-librespot[2010]: time="2024-06-29T13:56:11+02: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" Jun 29 13:56:11 teac-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 29 13:56:11 teac-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 29 13:56:11 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:11 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:11 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:11 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:11 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:56:11 teac-volumio volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 29 13:56:12 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:12 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:12 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:12 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:13 teac-volumio dhcpcd[1999]: eth0: leased 10.10.50.122 for 86400 seconds Jun 29 13:56:13 teac-volumio ifplugd(eth0)[674]: client: eth0: leased 10.10.50.122 for 86400 seconds Jun 29 13:56:13 teac-volumio dhcpcd[1999]: eth0: adding route to 10.10.50.0/24 Jun 29 13:56:13 teac-volumio avahi-daemon[517]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.10.50.122. Jun 29 13:56:13 teac-volumio avahi-daemon[517]: New relevant interface eth0.IPv4 for mDNS. Jun 29 13:56:13 teac-volumio dhcpcd[1999]: eth0: adding default route via 10.10.50.1 Jun 29 13:56:13 teac-volumio ifplugd(eth0)[674]: client: eth0: adding route to 10.10.50.0/24 Jun 29 13:56:13 teac-volumio avahi-daemon[517]: Registering new address record for 10.10.50.122 on eth0.IPv4. Jun 29 13:56:13 teac-volumio ifplugd(eth0)[674]: client: eth0: adding default route via 10.10.50.1 Jun 29 13:56:13 teac-volumio dhcpcd[1999]: forked to background, child pid 2038 Jun 29 13:56:13 teac-volumio ifplugd(eth0)[674]: client: forked to background, child pid 2038 Jun 29 13:56:13 teac-volumio ntpd[671]: Soliciting pool server 91.210.128.220 Jun 29 13:56:13 teac-volumio ifplugd(eth0)[674]: Program executed successfully. Jun 29 13:56:13 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:13 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:13 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:13 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin bluetooth to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin multiroom to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin metavolumio to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin cd_controller to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 29 13:56:13 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 29 13:56:14 teac-volumio ntpd[671]: Listen normally on 3 eth0 10.10.50.122:123 Jun 29 13:56:14 teac-volumio ntpd[671]: new interface(s) found: waking up resolver Jun 29 13:56:14 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:14 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:14 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:14 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:14 teac-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 29 13:56:14 teac-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Jun 29 13:56:14 teac-volumio systemd[1]: Stopped go-librespot Daemon. Jun 29 13:56:14 teac-volumio systemd[1]: Started go-librespot Daemon. Jun 29 13:56:14 teac-volumio go-librespot[2070]: Librespot-go daemon starting... Jun 29 13:56:14 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:14+02:00" level=info msg="generated new device id: 2efc3e62d670b2fd19d14873f0e3b5fb1511f2a4" Jun 29 13:56:14 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:14+02:00" level=debug msg="stored credentials found for redart-pl" Jun 29 13:56:14 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:14+02:00" level=debug msg="obtained new client token: AADZuBXwOnktkkeeQSse6FvhPHmR8vZytClFzRcQKgv8FBnjrERLWuwXjoV0FtAtZjFOjLSyr75RjE1zuw2pNf2slBxiSqxF5NvC6ulRmURmWMY2RbTh885v47f7zC4eluB/Umaah559dGF8iB8G4YqRvVn+RyByvdrJGnMwMEtTKE1rhz35og7+K3/mJ8GiGZ7GFcaCER9fNT1uSMtfAynLsxbAYOlaAc5pzv5dy8VOtJA3H+xkuNdEMXwMPsHf" Jun 29 13:56:14 teac-volumio volumio[715]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 29 13:56:14 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 29 13:56:14 teac-volumio volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 13:56:14 teac-volumio volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 13:56:14 teac-volumio volumio[715]: info: Starting MyVolumio Remote Streaming Endpoints Jun 29 13:56:14 teac-volumio volumio[715]: info: MyVolumio login type: Token Jun 29 13:56:14 teac-volumio volumio[715]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 29 13:56:14 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 29 13:56:14 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="completed keyexchange" Jun 29 13:56:15 teac-volumio ntpd[671]: Soliciting pool server 51.15.20.83 Jun 29 13:56:15 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:15 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:15 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:15 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="completed challenge" Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="authenticated as redart-pl" Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="authenticated as redart-pl" Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 29 13:56:15 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 29 13:56:15 teac-volumio volumio[715]: info: Starting Streaming Service Transparent Proxy Jun 29 13:56:15 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 29 13:56:15 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 29 13:56:15 teac-volumio volumio[715]: info: Streaming services startup Jun 29 13:56:15 teac-volumio volumio[715]: info: Starting Streaming Daemon Jun 29 13:56:16 teac-volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 29 13:56:16 teac-volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 13:56:16 teac-volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="dealer connection opened" Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jun 29 13:56:16 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="received connection id: NGI0N2RiYWUtY2FiNi00NDRhLTlmYjEtNGIxZDdjMGI2ZjcwK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXZrc2guZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzVDMUFGN0REMkEyMUQ1MDFFQkVBRDRCNjMwMDg4NjVCRTEwRTE2MDUyQkQ3NjgzRjI4ODI1MkVEQjE0RkVBQkI=" Jun 29 13:56:16 teac-volumio volumio[715]: info: Initializing connection to go-librespot Websocket Jun 29 13:56:16 teac-volumio volumio[715]: error: Cannot start Volumio Streaming Daemon Jun 29 13:56:16 teac-volumio volumio[715]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 29 13:56:16 teac-volumio volumio[715]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="new websocket client" Jun 29 13:56:16 teac-volumio volumio[715]: info: Connection to go-librespot Websocket established Jun 29 13:56:16 teac-volumio volumio[715]: info: Discovery: adding 3b5bc67f-238d-402f-a788-6796918c98d5 Jun 29 13:56:16 teac-volumio volumio[715]: info: Discovery: Found device TEAC Volumio Jun 29 13:56:16 teac-volumio volumio[715]: info: CoreCommandRouter::volumioGetState Jun 29 13:56:16 teac-volumio volumio[715]: info: CorePlayQueue::getTrack 0 Jun 29 13:56:16 teac-volumio ntpd[671]: Soliciting pool server 94.198.159.16 Jun 29 13:56:16 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:16+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 29 13:56:16 teac-volumio volumio[715]: error: MyVolumio Custom Token format not valid, refreshing it Jun 29 13:56:16 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:16 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:16 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:16 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:16 teac-volumio volumio[715]: STREAMING PROXY: Starting server on port 3245 Jun 29 13:56:16 teac-volumio volumio[715]: Node JS runtime: 14 Jun 29 13:56:17 teac-volumio volumio[715]: info: MyVolumio login type: Token Jun 29 13:56:17 teac-volumio ntpd[671]: Soliciting pool server 81.172.225.142 Jun 29 13:56:17 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:17 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:17 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:17 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:17 teac-volumio volumio[715]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="handling transfer player command from 836e125d3b5496371efa5817d0e601a011d226b2" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="fetched new page 0 with 25 items (list: 25)" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="shuffled context with seed 7363434631633201937 (len: 25, keep: 16)" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="loading track spotify:track:6WYnBDrYZhzufvPCtIuIA4 (paused: true, position: 5636ms)" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="emitting websocket event: will_play" Jun 29 13:56:18 teac-volumio volumio[715]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6WYnBDrYZhzufvPCtIuIA4","play_origin":"playlist"}} Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6WYnBDrYZhzufvPCtIuIA4" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="requested aes key for file c62838b089ffe50fe91ba9fdd9e61a018dba35ec, gid: 6WYnBDrYZhzufvPCtIuIA4" Jun 29 13:56:18 teac-volumio ntpd[671]: Soliciting pool server 185.224.145.68 Jun 29 13:56:18 teac-volumio volumio[715]: info: MyVolumio token set successfully Jun 29 13:56:18 teac-volumio volumio[715]: info: MYVOLUMIO: Adding device Jun 29 13:56:18 teac-volumio volumio[715]: info: MYVOLUMIO: Evaluating Server Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1602" Jun 29 13:56:18 teac-volumio ntpd[671]: Soliciting pool server 192.86.14.67 Jun 29 13:56:18 teac-volumio ntpd[671]: Soliciting pool server 80.50.102.114 Jun 29 13:56:18 teac-volumio lircd[1010]: lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:18 teac-volumio lircd[1010]: lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:18 teac-volumio lircd-0.10.1[1010]: Error: could not get file information for /dev/lirc0 Jun 29 13:56:18 teac-volumio lircd-0.10.1[1010]: default_init(): No such file or directory Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="fetched first chunk of 17, total size is 8890408 bytes" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1378" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="seek to 5636ms (diff: 100ms, samples: 248547, bytes: 215450)" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=info msg="loaded track \"Chomiczówka\" (uri: spotify:track:6WYnBDrYZhzufvPCtIuIA4, paused: true, position: 5636ms, duration: 214173ms)" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="emitting websocket event: metadata" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="emitting websocket event: active" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="sending successful reply for delaer request" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 29 13:56:18 teac-volumio volumio[715]: info: MyVolumio Plan changed: premium Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="fetched chunk 1/16, size: 524288" Jun 29 13:56:18 teac-volumio volumio[715]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 29 13:56:18 teac-volumio volumio[715]: info: Removing browser output: myVolumio user plan is not superstar Jun 29 13:56:18 teac-volumio volumio[715]: info: Removing audio output: Jun 29 13:56:18 teac-volumio volumio[715]: info: MYVOLUMIO: Adding device Jun 29 13:56:18 teac-volumio volumio[715]: info: MYVOLUMIO: Evaluating Server Jun 29 13:56:18 teac-volumio volumio[715]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6WYnBDrYZhzufvPCtIuIA4","name":"Chomiczówka","artist_names":["Sidney Polak"],"album_name":"Sidney Polak","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025088aa57aa2e00b4c7ae731e","position":5636,"duration":214173,"release_date":"year:2004 month:4 day:1","track_number":13,"disc_number":1}} Jun 29 13:56:18 teac-volumio volumio[715]: SPOTIFY: received: {"type":"active","data":null} Jun 29 13:56:18 teac-volumio volumio[715]: info: Aligning Spotify Volume to Volumio Volume Jun 29 13:56:18 teac-volumio volumio[715]: info: CoreCommandRouter::volumioGetState Jun 29 13:56:18 teac-volumio volumio[715]: info: CorePlayQueue::getTrack 0 Jun 29 13:56:18 teac-volumio volumio[715]: info: Setting Spotify Volume from Volumio: 0 Jun 29 13:56:18 teac-volumio volumio[715]: info: Remote config written successfully Jun 29 13:56:18 teac-volumio volumio[715]: info: Starting Tunnel 1 Jun 29 13:56:18 teac-volumio volumio[715]: info: Starting Tunnel Connection Checker Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="fetched chunk 2/16, size: 524288" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=trace msg="emitting websocket event: paused" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="handling update_context player command from 836e125d3b5496371efa5817d0e601a011d226b2" Jun 29 13:56:18 teac-volumio volumio[715]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6WYnBDrYZhzufvPCtIuIA4","play_origin":"playlist"}} Jun 29 13:56:18 teac-volumio volumio[715]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 13:56:18 teac-volumio volumio[715]: TypeError: Cannot read property 'service' of undefined Jun 29 13:56:18 teac-volumio volumio[715]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jun 29 13:56:18 teac-volumio volumio[715]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jun 29 13:56:18 teac-volumio volumio[715]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jun 29 13:56:18 teac-volumio volumio[715]: at WebSocket.emit (events.js:315:20) Jun 29 13:56:18 teac-volumio volumio[715]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Jun 29 13:56:18 teac-volumio volumio[715]: at Receiver.emit (events.js:315:20) Jun 29 13:56:18 teac-volumio volumio[715]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Jun 29 13:56:18 teac-volumio volumio[715]: at internal/process/task_queues.js:149:7 Jun 29 13:56:18 teac-volumio volumio[715]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jun 29 13:56:18 teac-volumio volumio[715]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Jun 29 13:56:18 teac-volumio volumio[715]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jun 29 13:56:18 teac-volumio volumio[715]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="fetched chunk 3/16, size: 524288" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="sending successful reply for delaer request" Jun 29 13:56:18 teac-volumio go-librespot[2070]: time="2024-06-29T13:56:18+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 29 13:56:19 teac-volumio sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-29 13:55 Jun 29 13:56:19 teac-volumio sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 13:56:19 teac-volumio ntpd[671]: Soliciting pool server 162.159.200.123 Jun 29 13:56:19 teac-volumio ntpd[671]: Soliciting pool server 89.25.168.148 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"