-- Logs begin at Thu 2024-10-24 08:13:54 CEST, end at Sun 2024-10-27 12:05:06 CET. -- Oct 27 12:04:01 311verona-p1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 27 12:04:01 311verona-p1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15708. Oct 27 12:04:01 311verona-p1 systemd[1]: Stopped go-librespot Daemon. Oct 27 12:04:01 311verona-p1 systemd[1]: Started go-librespot Daemon. Oct 27 12:04:01 311verona-p1 go-librespot[12108]: Librespot-go daemon starting... Oct 27 12:04:01 311verona-p1 go-librespot[12108]: time="2024-10-27T12:04:01+01:00" level=info msg="generated new device id: 728ab4df3d9b9f162020e58698adb98c2add68d4" Oct 27 12:04:01 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:01 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:04 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:04 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:07 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:07 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:10 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:10 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:11 311verona-p1 go-librespot[12108]: time="2024-10-27T12:04:11+01:00" level=fatal msg="failed creating app" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Oct 27 12:04:11 311verona-p1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 12:04:11 311verona-p1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 12:04:13 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:13 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:14 311verona-p1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 27 12:04:14 311verona-p1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15709. Oct 27 12:04:14 311verona-p1 systemd[1]: Stopped go-librespot Daemon. Oct 27 12:04:14 311verona-p1 systemd[1]: Started go-librespot Daemon. Oct 27 12:04:14 311verona-p1 go-librespot[12154]: Librespot-go daemon starting... Oct 27 12:04:14 311verona-p1 go-librespot[12154]: time="2024-10-27T12:04:14+01:00" level=info msg="generated new device id: 67d48d9ec5f37c08497f6bea2383acb02365fd5c" Oct 27 12:04:16 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:16 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:19 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:19 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:22 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:22 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:24 311verona-p1 go-librespot[12154]: time="2024-10-27T12:04:24+01:00" level=fatal msg="failed creating app" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Oct 27 12:04:24 311verona-p1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 12:04:24 311verona-p1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 12:04:25 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:25 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:27 311verona-p1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 27 12:04:27 311verona-p1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15710. Oct 27 12:04:27 311verona-p1 systemd[1]: Stopped go-librespot Daemon. Oct 27 12:04:27 311verona-p1 systemd[1]: Started go-librespot Daemon. Oct 27 12:04:27 311verona-p1 go-librespot[12161]: Librespot-go daemon starting... Oct 27 12:04:27 311verona-p1 go-librespot[12161]: time="2024-10-27T12:04:27+01:00" level=info msg="generated new device id: 7882786214788cfbde122e0d289276eebe071577" Oct 27 12:04:28 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:28 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:31 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:31 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:34 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:34 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:37 311verona-p1 go-librespot[12161]: time="2024-10-27T12:04:37+01:00" level=fatal msg="failed creating app" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Oct 27 12:04:37 311verona-p1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 12:04:37 311verona-p1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 12:04:37 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:37 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:40 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:40 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:40 311verona-p1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 27 12:04:40 311verona-p1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15711. Oct 27 12:04:40 311verona-p1 systemd[1]: Stopped go-librespot Daemon. Oct 27 12:04:40 311verona-p1 systemd[1]: Started go-librespot Daemon. Oct 27 12:04:40 311verona-p1 go-librespot[12171]: Librespot-go daemon starting... Oct 27 12:04:40 311verona-p1 go-librespot[12171]: time="2024-10-27T12:04:40+01:00" level=info msg="generated new device id: 7594fab50b0df71b82c7c2fd2916855cb3fe1be5" Oct 27 12:04:43 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:43 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:46 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:46 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:49 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:49 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:50 311verona-p1 go-librespot[12171]: time="2024-10-27T12:04:50+01:00" level=fatal msg="failed creating app" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Oct 27 12:04:50 311verona-p1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 12:04:50 311verona-p1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 12:04:52 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:52 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:54 311verona-p1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 27 12:04:54 311verona-p1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15712. Oct 27 12:04:54 311verona-p1 systemd[1]: Stopped go-librespot Daemon. Oct 27 12:04:54 311verona-p1 systemd[1]: Started go-librespot Daemon. Oct 27 12:04:54 311verona-p1 go-librespot[12178]: Librespot-go daemon starting... Oct 27 12:04:54 311verona-p1 go-librespot[12178]: time="2024-10-27T12:04:54+01:00" level=info msg="generated new device id: c02312b607c96047d4167ac2b17c7810e64d387f" Oct 27 12:04:55 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:55 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:04:58 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:04:58 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:05:01 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:05:01 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:05:04 311verona-p1 go-librespot[12178]: time="2024-10-27T12:05:04+01:00" level=fatal msg="failed creating app" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Oct 27 12:05:04 311verona-p1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 27 12:05:04 311verona-p1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 27 12:05:04 311verona-p1 volumio[954]: info: Initializing connection to go-librespot Websocket Oct 27 12:05:04 311verona-p1 volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 27 12:05:06 311verona-p1 volumio[954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 12:05:06 311verona-p1 volumio[954]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 27 12:05:06 311verona-p1 volumio[954]: code: 'auth/network-request-failed', Oct 27 12:05:06 311verona-p1 volumio[954]: a: null Oct 27 12:05:06 311verona-p1 volumio[954]: } Oct 27 12:05:06 311verona-p1 volumio[954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 12:05:06 311verona-p1 sudo[12197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 12:04 Oct 27 12:05:06 311verona-p1 sudo[12197]: 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"