-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-09-26 10:45:03 UTC. -- Sep 26 10:44:00 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:00 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1624. Sep 26 10:44:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:00 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:00 volumio go-librespot[15631]: Librespot-go daemon starting... Sep 26 10:44:00 volumio go-librespot[15631]: time="2024-09-26T10:44:00Z" level=info msg="generated new device id: dfd5529c1c27e1383771a1a6a882016e0a8ece76" Sep 26 10:44:00 volumio go-librespot[15631]: time="2024-09-26T10:44:00Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:00Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:03 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:03 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1625. Sep 26 10:44:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:03 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:03 volumio go-librespot[15639]: Librespot-go daemon starting... Sep 26 10:44:03 volumio go-librespot[15639]: time="2024-09-26T10:44:03Z" level=info msg="generated new device id: a74c2fa7e475fd7ed02c03df02be3e286bd708f8" Sep 26 10:44:03 volumio go-librespot[15639]: time="2024-09-26T10:44:03Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:03Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:05 volumio ntpd[625]: Soliciting pool server 103.221.223.185 Sep 26 10:44:06 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:06 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1626. Sep 26 10:44:06 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:06 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:06 volumio go-librespot[15647]: Librespot-go daemon starting... Sep 26 10:44:07 volumio go-librespot[15647]: time="2024-09-26T10:44:07Z" level=info msg="generated new device id: 7d0e25b757264fba97285df6191f297fabcb8331" Sep 26 10:44:07 volumio go-librespot[15647]: time="2024-09-26T10:44:07Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:07Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:09 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:09 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1627. Sep 26 10:44:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:10 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:10 volumio go-librespot[15657]: Librespot-go daemon starting... Sep 26 10:44:10 volumio go-librespot[15657]: time="2024-09-26T10:44:10Z" level=info msg="generated new device id: acc029a618f825cbec25fd552443af5f60ac73df" Sep 26 10:44:10 volumio go-librespot[15657]: time="2024-09-26T10:44:10Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:10Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:12 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:12 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1628. Sep 26 10:44:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:13 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:13 volumio go-librespot[15666]: Librespot-go daemon starting... Sep 26 10:44:13 volumio go-librespot[15666]: time="2024-09-26T10:44:13Z" level=info msg="generated new device id: 28e70ac4272c2509def0e171c04ea8f04dffb4db" Sep 26 10:44:13 volumio ntpd[625]: Soliciting pool server 45.252.250.189 Sep 26 10:44:13 volumio ntpd[625]: Soliciting pool server 160.25.232.6 Sep 26 10:44:13 volumio go-librespot[15666]: time="2024-09-26T10:44:13Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:13Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:15 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:15 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1629. Sep 26 10:44:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:16 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:16 volumio go-librespot[15675]: Librespot-go daemon starting... Sep 26 10:44:16 volumio go-librespot[15675]: time="2024-09-26T10:44:16Z" level=info msg="generated new device id: d03c8c61f65950686240c9ce3778c648d96631fd" Sep 26 10:44:16 volumio go-librespot[15675]: time="2024-09-26T10:44:16Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:16Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:18 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:18 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1630. Sep 26 10:44:19 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:19 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:19 volumio go-librespot[15683]: Librespot-go daemon starting... Sep 26 10:44:20 volumio go-librespot[15683]: time="2024-09-26T10:44:20Z" level=info msg="generated new device id: d2f92cdae666de95d4547f25727a7218b6cf8849" Sep 26 10:44:20 volumio go-librespot[15683]: time="2024-09-26T10:44:20Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:20Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:21 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:21 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1631. Sep 26 10:44:23 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:23 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:23 volumio go-librespot[15691]: Librespot-go daemon starting... Sep 26 10:44:23 volumio go-librespot[15691]: time="2024-09-26T10:44:23Z" level=info msg="generated new device id: 7d3bf046b64ce87d5f786404d57c86dc653fcbd1" Sep 26 10:44:23 volumio go-librespot[15691]: time="2024-09-26T10:44:23Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:23Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:24 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:24 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1632. Sep 26 10:44:26 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:26 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:26 volumio go-librespot[15699]: Librespot-go daemon starting... Sep 26 10:44:26 volumio go-librespot[15699]: time="2024-09-26T10:44:26Z" level=info msg="generated new device id: 5d6cbfe9b5da08fdd6a532c5483939c236ce6e11" Sep 26 10:44:26 volumio go-librespot[15699]: time="2024-09-26T10:44:26Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:26Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:27 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:27 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1633. Sep 26 10:44:29 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:29 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:29 volumio go-librespot[15708]: Librespot-go daemon starting... Sep 26 10:44:29 volumio go-librespot[15708]: time="2024-09-26T10:44:29Z" level=info msg="generated new device id: 1d1b4ea77622363752b03a0c941825240db01a6d" Sep 26 10:44:29 volumio go-librespot[15708]: time="2024-09-26T10:44:29Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:29Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:30 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:30 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1634. Sep 26 10:44:32 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:32 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:32 volumio go-librespot[15729]: Librespot-go daemon starting... Sep 26 10:44:33 volumio go-librespot[15729]: time="2024-09-26T10:44:33Z" level=info msg="generated new device id: ddb2f37751f28d8d4b6540ccec899b860570c1e4" Sep 26 10:44:33 volumio go-librespot[15729]: time="2024-09-26T10:44:33Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:33Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:33 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:33 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:33 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:33 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:33 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:33 volumio volumio[723]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 26 10:44:33 volumio volumio[723]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 26 10:44:33 volumio volumio[723]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:34 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:34 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:34 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1635. Sep 26 10:44:36 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:36 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:36 volumio go-librespot[15745]: Librespot-go daemon starting... Sep 26 10:44:36 volumio go-librespot[15745]: time="2024-09-26T10:44:36Z" level=info msg="generated new device id: 498fe99371fc1d62b5b1d80c346a52e39d391b25" Sep 26 10:44:36 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:36 volumio go-librespot[15745]: time="2024-09-26T10:44:36Z" level=debug msg="new websocket client" Sep 26 10:44:36 volumio volumio[723]: info: Connection to go-librespot Websocket established Sep 26 10:44:36 volumio go-librespot[15745]: time="2024-09-26T10:44:36Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:36Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:36 volumio volumio[723]: info: Connection to go-librespot Websocket closed Sep 26 10:44:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:39 volumio volumio[723]: info: Getting Spotify volume Sep 26 10:44:39 volumio volumio[723]: (node:723) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:39 volumio volumio[723]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 26 10:44:39 volumio volumio[723]: (node:723) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 67) Sep 26 10:44:39 volumio volumio[723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 26 10:44:39 volumio volumio[723]: info: CoreCommandRouter::volumioGetState Sep 26 10:44:39 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:39 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:39 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:39 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:39 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:39 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1636. Sep 26 10:44:39 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:39 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:39 volumio go-librespot[15753]: Librespot-go daemon starting... Sep 26 10:44:39 volumio go-librespot[15753]: time="2024-09-26T10:44:39Z" level=info msg="generated new device id: 95fc5db805470e6a41530616c6127d613917fa95" Sep 26 10:44:39 volumio go-librespot[15753]: time="2024-09-26T10:44:39Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:39Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:42 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:42 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1637. Sep 26 10:44:42 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:42 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:42 volumio go-librespot[15762]: Librespot-go daemon starting... Sep 26 10:44:42 volumio go-librespot[15762]: time="2024-09-26T10:44:42Z" level=info msg="generated new device id: 40797d13718e3ef822634efdc10c0945594607f5" Sep 26 10:44:42 volumio go-librespot[15762]: time="2024-09-26T10:44:42Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:42Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:45 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:45 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1638. Sep 26 10:44:45 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:45 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:45 volumio go-librespot[15771]: Librespot-go daemon starting... Sep 26 10:44:46 volumio go-librespot[15771]: time="2024-09-26T10:44:46Z" level=info msg="generated new device id: 6fc2b5d3e76b53c9c1444e7618fef4f03c867157" Sep 26 10:44:46 volumio go-librespot[15771]: time="2024-09-26T10:44:46Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:46Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:48 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:48 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1639. Sep 26 10:44:49 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:49 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:49 volumio go-librespot[15779]: Librespot-go daemon starting... Sep 26 10:44:49 volumio go-librespot[15779]: time="2024-09-26T10:44:49Z" level=info msg="generated new device id: ba967e3bee61ae9dbf2ff9fbdd9ccf48a9092809" Sep 26 10:44:49 volumio go-librespot[15779]: time="2024-09-26T10:44:49Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:49Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:44:49 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:44:49 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:44:49 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:44:51 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:51 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1640. Sep 26 10:44:52 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:52 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:52 volumio go-librespot[15787]: Librespot-go daemon starting... Sep 26 10:44:52 volumio go-librespot[15787]: time="2024-09-26T10:44:52Z" level=info msg="generated new device id: 2dec47555eadf3108731a2c309bd815182bc1872" Sep 26 10:44:52 volumio go-librespot[15787]: time="2024-09-26T10:44:52Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:52Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:54 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:54 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1641. Sep 26 10:44:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:55 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:55 volumio go-librespot[15797]: Librespot-go daemon starting... Sep 26 10:44:55 volumio go-librespot[15797]: time="2024-09-26T10:44:55Z" level=info msg="generated new device id: c7a4c208b97d5dd88bd09cf500d3fb956a95f2a1" Sep 26 10:44:55 volumio go-librespot[15797]: time="2024-09-26T10:44:55Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:55Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:44:57 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:44:57 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:44:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:44:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1642. Sep 26 10:44:58 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:44:58 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:44:58 volumio go-librespot[15805]: Librespot-go daemon starting... Sep 26 10:44:59 volumio go-librespot[15805]: time="2024-09-26T10:44:59Z" level=info msg="generated new device id: 0bcaad5477d7bbe076fffcbed5ac54ba1823c2c7" Sep 26 10:44:59 volumio go-librespot[15805]: time="2024-09-26T10:44:59Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:44:59Z is before 2025-12-08T00:00:00Z" Sep 26 10:44:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:44:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:45:00 volumio volumio[723]: info: Initializing connection to go-librespot Websocket Sep 26 10:45:00 volumio volumio[723]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 10:45:01 volumio ntpd[625]: Soliciting pool server 2401:5b60:0:2::21 Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::servicePushState Sep 26 10:45:02 volumio volumio[723]: info: CoreStateMachine::pushState Sep 26 10:45:02 volumio volumio[723]: info: CoreCommandRouter::volumioPushState Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: SPOTIFY VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: VOLUMIO VOLUME 100 Sep 26 10:45:02 volumio volumio[723]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 26 10:45:02 volumio volumio[723]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 26 10:45:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 10:45:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1643. Sep 26 10:45:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 10:45:02 volumio systemd[1]: Started go-librespot Daemon. Sep 26 10:45:02 volumio go-librespot[15815]: Librespot-go daemon starting... Sep 26 10:45:02 volumio go-librespot[15815]: time="2024-09-26T10:45:02Z" level=info msg="generated new device id: 2fa64de2c3e1c6a9f1e8ba3e820a80aa13240be8" Sep 26 10:45:02 volumio go-librespot[15815]: time="2024-09-26T10:45:02Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-26T10:45:02Z is before 2025-12-08T00:00:00Z" Sep 26 10:45:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 10:45:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 10:45:02 volumio volumio[723]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 10:45:02 volumio volumio[723]: Error: certificate is not yet valid Sep 26 10:45:02 volumio volumio[723]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Sep 26 10:45:02 volumio volumio[723]: at TLSSocket.emit (events.js:315:20) Sep 26 10:45:02 volumio volumio[723]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Sep 26 10:45:02 volumio volumio[723]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Sep 26 10:45:02 volumio volumio[723]: code: 'CERT_NOT_YET_VALID' Sep 26 10:45:02 volumio volumio[723]: } Sep 26 10:45:02 volumio volumio[723]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 10:45:03 volumio sudo[15833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-26 10:44 Sep 26 10:45:03 volumio sudo[15833]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"