-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-09-26 15:48:25 UTC. -- Sep 26 15:47:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 190. Sep 26 15:47:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:00 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:00 volumio go-librespot[3887]: Librespot-go daemon starting... Sep 26 15:47:00 volumio go-librespot[3887]: time="2024-09-26T15:47:00Z" level=info msg="generated new device id: 6257e5d5b802b52f7440f2f562ac50af74d5b12b" Sep 26 15:47:00 volumio go-librespot[3887]: time="2024-09-26T15:47: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-26T15:47:00Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:02 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:02 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 191. Sep 26 15:47:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:03 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:03 volumio go-librespot[3901]: Librespot-go daemon starting... Sep 26 15:47:03 volumio go-librespot[3901]: time="2024-09-26T15:47:03Z" level=info msg="generated new device id: c780c3b3e7e0ec6da23f2eba79ed01fbfa9f5232" Sep 26 15:47:04 volumio go-librespot[3901]: time="2024-09-26T15:47:04Z" 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-26T15:47:04Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:05 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:05 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 192. Sep 26 15:47:07 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:07 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:07 volumio go-librespot[3915]: Librespot-go daemon starting... Sep 26 15:47:07 volumio go-librespot[3915]: time="2024-09-26T15:47:07Z" level=info msg="generated new device id: 604661e5fae2759fb10a419f44e4430bcb5dd17f" Sep 26 15:47:07 volumio go-librespot[3915]: time="2024-09-26T15:47: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-26T15:47:07Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:08 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:08 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 193. Sep 26 15:47:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:10 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:10 volumio go-librespot[3931]: Librespot-go daemon starting... Sep 26 15:47:10 volumio go-librespot[3931]: time="2024-09-26T15:47:10Z" level=info msg="generated new device id: 2de7be0123752af3b88dcacd1e18fb5df6928fcc" Sep 26 15:47:10 volumio go-librespot[3931]: time="2024-09-26T15:47: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-26T15:47:10Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:11 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:11 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 194. Sep 26 15:47:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:13 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:13 volumio go-librespot[3945]: Librespot-go daemon starting... Sep 26 15:47:13 volumio go-librespot[3945]: time="2024-09-26T15:47:13Z" level=info msg="generated new device id: 08ec1054ff7d01f068f0162870bbeaed8ecb6766" Sep 26 15:47:13 volumio go-librespot[3945]: time="2024-09-26T15:47: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-26T15:47:13Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:14 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:14 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 195. Sep 26 15:47:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:16 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:16 volumio go-librespot[3959]: Librespot-go daemon starting... Sep 26 15:47:16 volumio go-librespot[3959]: time="2024-09-26T15:47:16Z" level=info msg="generated new device id: ff24b80aad01c552ada6355fafbd4565a425aedf" Sep 26 15:47:17 volumio go-librespot[3959]: time="2024-09-26T15:47:17Z" 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-26T15:47:16Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:17 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:17 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:19 volumio ntpd[622]: Soliciting pool server 103.130.217.41 Sep 26 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 196. Sep 26 15:47:20 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:20 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:20 volumio go-librespot[3974]: Librespot-go daemon starting... Sep 26 15:47:20 volumio go-librespot[3974]: time="2024-09-26T15:47:20Z" level=info msg="generated new device id: 34b1810cf6930ee101ea96d4304c16a6b17afa8c" Sep 26 15:47:20 volumio ntpd[622]: Soliciting pool server 2400:e920:0:5::14 Sep 26 15:47:20 volumio ntpd[622]: Soliciting pool server 103.130.217.41 Sep 26 15:47:20 volumio go-librespot[3974]: time="2024-09-26T15:47: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-26T15:47:20Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:20 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:20 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 197. Sep 26 15:47:23 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:23 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:23 volumio go-librespot[3989]: Librespot-go daemon starting... Sep 26 15:47:23 volumio go-librespot[3989]: time="2024-09-26T15:47:23Z" level=info msg="generated new device id: 65bde9afa19d8db8ba242d2fc382deef9814d3c3" Sep 26 15:47:23 volumio go-librespot[3989]: time="2024-09-26T15:47: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-26T15:47:23Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:23 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:23 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:24 volumio ntpd[622]: Soliciting pool server 103.184.124.254 Sep 26 15:47:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 198. Sep 26 15:47:26 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:26 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:26 volumio go-librespot[4006]: Librespot-go daemon starting... Sep 26 15:47:26 volumio go-librespot[4006]: time="2024-09-26T15:47:26Z" level=info msg="generated new device id: b1b1a3924dbbf84ce3db9b2a1a80ed57d7246d55" Sep 26 15:47:26 volumio go-librespot[4006]: time="2024-09-26T15:47: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-26T15:47:26Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:26 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:26 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 199. Sep 26 15:47:29 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:29 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:29 volumio go-librespot[4021]: Librespot-go daemon starting... Sep 26 15:47:29 volumio go-librespot[4021]: time="2024-09-26T15:47:29Z" level=info msg="generated new device id: 7195776409d71b70cf3a0a3c16a9b56534c95496" Sep 26 15:47:29 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:29 volumio go-librespot[4021]: time="2024-09-26T15:47:29Z" level=debug msg="new websocket client" Sep 26 15:47:29 volumio volumio[722]: info: Connection to go-librespot Websocket established Sep 26 15:47:30 volumio go-librespot[4021]: time="2024-09-26T15:47:30Z" 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-26T15:47:30Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:30 volumio volumio[722]: info: Connection to go-librespot Websocket closed Sep 26 15:47:32 volumio volumio[722]: info: Getting Spotify volume Sep 26 15:47:32 volumio volumio[722]: (node:722) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:32 volumio volumio[722]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 26 15:47:32 volumio volumio[722]: (node:722) 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: 10) Sep 26 15:47:32 volumio volumio[722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 26 15:47:32 volumio volumio[722]: info: CoreCommandRouter::volumioGetState Sep 26 15:47:32 volumio volumio[722]: info: CorePlayQueue::getTrack 11 Sep 26 15:47:32 volumio volumio[722]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 15:47:33 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:33 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 200. Sep 26 15:47:33 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:33 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:33 volumio go-librespot[4035]: Librespot-go daemon starting... Sep 26 15:47:33 volumio go-librespot[4035]: time="2024-09-26T15:47:33Z" level=info msg="generated new device id: 4074eae5f9564c9f3d7c5fd37c40dfb85dc9eac4" Sep 26 15:47:33 volumio go-librespot[4035]: time="2024-09-26T15:47: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-26T15:47:33Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:36 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:36 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 201. Sep 26 15:47:36 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:36 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:36 volumio go-librespot[4049]: Librespot-go daemon starting... Sep 26 15:47:36 volumio go-librespot[4049]: time="2024-09-26T15:47:36Z" level=info msg="generated new device id: 07b660489134cd67d899b639cc4400666322df94" Sep 26 15:47:36 volumio go-librespot[4049]: time="2024-09-26T15:47: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-26T15:47:36Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:39 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:39 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 202. Sep 26 15:47:39 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:39 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:39 volumio go-librespot[4063]: Librespot-go daemon starting... Sep 26 15:47:39 volumio go-librespot[4063]: time="2024-09-26T15:47:39Z" level=info msg="generated new device id: 1372339470715f20a6720a14e3c252ee220082df" Sep 26 15:47:39 volumio go-librespot[4063]: time="2024-09-26T15:47: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-26T15:47:39Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:42 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:42 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 203. Sep 26 15:47:42 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:42 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:42 volumio go-librespot[4079]: Librespot-go daemon starting... Sep 26 15:47:42 volumio go-librespot[4079]: time="2024-09-26T15:47:42Z" level=info msg="generated new device id: c69339ce1d255411cb17e158523efcb05b71fceb" Sep 26 15:47:43 volumio go-librespot[4079]: time="2024-09-26T15:47:43Z" 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-26T15:47:43Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:45 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:45 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 204. Sep 26 15:47:46 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:46 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:46 volumio go-librespot[4095]: Librespot-go daemon starting... Sep 26 15:47:46 volumio go-librespot[4095]: time="2024-09-26T15:47:46Z" level=info msg="generated new device id: 169c72e7154c557252ee5d772a5e4ad3246013fd" Sep 26 15:47:46 volumio go-librespot[4095]: time="2024-09-26T15:47: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-26T15:47:46Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:48 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:48 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 205. Sep 26 15:47:49 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:49 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:49 volumio go-librespot[4113]: Librespot-go daemon starting... Sep 26 15:47:49 volumio go-librespot[4113]: time="2024-09-26T15:47:49Z" level=info msg="generated new device id: 2885dc81ad685f42ea4abb518019295e41684b91" Sep 26 15:47:49 volumio go-librespot[4113]: time="2024-09-26T15:47: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-26T15:47:49Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:51 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:51 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 206. Sep 26 15:47:52 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:52 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:52 volumio go-librespot[4127]: Librespot-go daemon starting... Sep 26 15:47:52 volumio go-librespot[4127]: time="2024-09-26T15:47:52Z" level=info msg="generated new device id: 8db2139b96145cc9c52624a5335e2dcca38622fd" Sep 26 15:47:52 volumio go-librespot[4127]: time="2024-09-26T15:47: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-26T15:47:52Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:54 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:54 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 207. Sep 26 15:47:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:55 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:55 volumio go-librespot[4141]: Librespot-go daemon starting... Sep 26 15:47:55 volumio go-librespot[4141]: time="2024-09-26T15:47:55Z" level=info msg="generated new device id: 6b5907fe3466867ca57bfcb3af41be801dd47055" Sep 26 15:47:56 volumio go-librespot[4141]: time="2024-09-26T15:47:56Z" 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-26T15:47:55Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:47:57 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:47:57 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 208. Sep 26 15:47:59 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:47:59 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:47:59 volumio go-librespot[4157]: Librespot-go daemon starting... Sep 26 15:47:59 volumio go-librespot[4157]: time="2024-09-26T15:47:59Z" level=info msg="generated new device id: 867ad92b6b8f05219f2cb449d37ee111a97fb6fc" Sep 26 15:47:59 volumio go-librespot[4157]: time="2024-09-26T15:47: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-26T15:47:59Z is before 2025-12-08T00:00:00Z" Sep 26 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:00 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:00 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 209. Sep 26 15:48:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:02 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:02 volumio go-librespot[4171]: Librespot-go daemon starting... Sep 26 15:48:02 volumio go-librespot[4171]: time="2024-09-26T15:48:02Z" level=info msg="generated new device id: 924ec14b7996b01d03cc3bb63a5e9b17994cecca" Sep 26 15:48:02 volumio go-librespot[4171]: time="2024-09-26T15:48: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-26T15:48:02Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:03 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:03 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Sep 26 15:48:05 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:05 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:05 volumio go-librespot[4185]: Librespot-go daemon starting... Sep 26 15:48:05 volumio go-librespot[4185]: time="2024-09-26T15:48:05Z" level=info msg="generated new device id: 1fc8dbd34ae01da2468bf95991d8a9c573f2377e" Sep 26 15:48:05 volumio go-librespot[4185]: time="2024-09-26T15:48:05Z" 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-26T15:48:05Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:06 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:06 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Sep 26 15:48:08 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:08 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:08 volumio go-librespot[4201]: Librespot-go daemon starting... Sep 26 15:48:08 volumio go-librespot[4201]: time="2024-09-26T15:48:08Z" level=info msg="generated new device id: fd361a64cda7c13fe8ce67c46a44fec22faf92c1" Sep 26 15:48:09 volumio go-librespot[4201]: time="2024-09-26T15:48:09Z" 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-26T15:48:09Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:09 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:09 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Sep 26 15:48:12 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:12 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:12 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:12 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:12 volumio go-librespot[4215]: Librespot-go daemon starting... Sep 26 15:48:12 volumio go-librespot[4215]: time="2024-09-26T15:48:12Z" level=info msg="generated new device id: fbffe27a9f7d38744ea9976dc590d2763c01e950" Sep 26 15:48:12 volumio go-librespot[4215]: time="2024-09-26T15:48:12Z" 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-26T15:48:12Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:15 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:15 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Sep 26 15:48:15 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:15 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:15 volumio go-librespot[4233]: Librespot-go daemon starting... Sep 26 15:48:15 volumio go-librespot[4233]: time="2024-09-26T15:48:15Z" level=info msg="generated new device id: ef542fe9a0fd9319de50dbf1605b8f5788cc6612" Sep 26 15:48:15 volumio go-librespot[4233]: time="2024-09-26T15:48:15Z" 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-26T15:48:15Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:18 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:18 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Sep 26 15:48:18 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:18 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:18 volumio go-librespot[4247]: Librespot-go daemon starting... Sep 26 15:48:18 volumio go-librespot[4247]: time="2024-09-26T15:48:18Z" level=info msg="generated new device id: 401288f405759c07718fa28e67a741884b8b6575" Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 11 Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:18 volumio volumio[722]: info: Prefetching next song Sep 26 15:48:18 volumio volumio[722]: info: DOING PREFETCH IN MPD Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Right here waiting for you (Richard Marx).flac" Sep 26 15:48:18 volumio volumio[722]: info: Sep 26 15:48:18 volumio volumio[722]: ---------------------------- MPD announces system playlist update Sep 26 15:48:18 volumio go-librespot[4247]: time="2024-09-26T15:48:18Z" 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-26T15:48:18Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:18 volumio volumio[722]: info: Ignoring MPD Status Update Sep 26 15:48:18 volumio volumio[722]: info: sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Right here waiting for you (Richard Marx).flac" took 1 milliseconds Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 26 15:48:18 volumio volumio[722]: info: Sep 26 15:48:18 volumio volumio[722]: ---------------------------- MPD announces state update: options Sep 26 15:48:18 volumio volumio[722]: info: ------------------------------ 3ms Sep 26 15:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:18 volumio volumio[722]: info: sendMpdCommand consume 1 took 3 milliseconds Sep 26 15:48:18 volumio volumio[722]: info: ControllerMpd::getState Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand status Sep 26 15:48:18 volumio volumio[722]: info: sendMpdCommand status took 2 milliseconds Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::parseState Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 26 15:48:18 volumio volumio[722]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 26 15:48:18 volumio volumio[722]: verbose: ControllerMpd::parseTrackInfo Sep 26 15:48:18 volumio volumio[722]: info: ControllerMpd::pushState Sep 26 15:48:18 volumio volumio[722]: info: CoreCommandRouter::servicePushState Sep 26 15:48:18 volumio volumio[722]: info: CoreStateMachine::startPlaybackTimer Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:18 volumio volumio[722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":429531,"duration":434,"samplerate":44.1,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Engles - Hotel California.dts","artist":null,"album":null,"uri":"NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Engles - Hotel California.dts","trackType":"dts"} Sep 26 15:48:18 volumio volumio[722]: verbose: CURRENT POSITION 25 Sep 26 15:48:18 volumio volumio[722]: info: CoreStateMachine::syncState stateService play Sep 26 15:48:18 volumio volumio[722]: info: CoreStateMachine::syncState currentStatus play Sep 26 15:48:18 volumio volumio[722]: info: Received an update from plugin. extracting info from payload Sep 26 15:48:18 volumio volumio[722]: info: CoreStateMachine::pushState Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:18 volumio volumio[722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 15:48:18 volumio volumio[722]: info: CoreCommandRouter::volumioPushState Sep 26 15:48:18 volumio volumio[722]: info: CoreStateMachine::pushState Sep 26 15:48:18 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:18 volumio volumio[722]: info: CoreCommandRouter::volumioPushState Sep 26 15:48:18 volumio volumio[722]: info: ------------------------------ 24ms Sep 26 15:48:18 volumio volumio[722]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 25 Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 33 Sep 26 15:48:19 volumio volumio[722]: info: Prefetching next song Sep 26 15:48:19 volumio volumio[722]: info: DOING PREFETCH IN MPD Sep 26 15:48:19 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/The day you went away (M2M).flac" Sep 26 15:48:19 volumio volumio[722]: info: CoreStateMachine::startPlaybackTimer Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 33 Sep 26 15:48:19 volumio volumio[722]: info: Sep 26 15:48:19 volumio volumio[722]: ---------------------------- MPD announces system playlist update Sep 26 15:48:19 volumio volumio[722]: info: Ignoring MPD Status Update Sep 26 15:48:19 volumio volumio[722]: info: sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/The day you went away (M2M).flac" took 1 milliseconds Sep 26 15:48:19 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 26 15:48:19 volumio volumio[722]: info: ------------------------------ 1ms Sep 26 15:48:19 volumio volumio[722]: info: sendMpdCommand consume 1 took 1 milliseconds Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 33 Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 67 Sep 26 15:48:19 volumio volumio[722]: info: Prefetching next song Sep 26 15:48:19 volumio volumio[722]: info: DOING PREFETCH IN MPD Sep 26 15:48:19 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Sway - The Pussycat Dolls [FLAC Lossless].flac" Sep 26 15:48:19 volumio volumio[722]: info: CoreStateMachine::startPlaybackTimer Sep 26 15:48:19 volumio volumio[722]: info: CorePlayQueue::getTrack 67 Sep 26 15:48:19 volumio volumio[722]: info: Sep 26 15:48:19 volumio volumio[722]: ---------------------------- MPD announces system playlist update Sep 26 15:48:19 volumio volumio[722]: info: Ignoring MPD Status Update Sep 26 15:48:19 volumio volumio[722]: info: sendMpdCommand add "NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Sway - The Pussycat Dolls [FLAC Lossless].flac" took 1 milliseconds Sep 26 15:48:19 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 26 15:48:19 volumio volumio[722]: info: ------------------------------ 1ms Sep 26 15:48:19 volumio volumio[722]: info: sendMpdCommand consume 1 took 1 milliseconds Sep 26 15:48:21 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:21 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Sep 26 15:48:21 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:21 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:21 volumio go-librespot[4266]: Librespot-go daemon starting... Sep 26 15:48:21 volumio go-librespot[4266]: time="2024-09-26T15:48:21Z" level=info msg="generated new device id: 8d37c57dc84b390f1395025c2d2c44588046c6e1" Sep 26 15:48:22 volumio go-librespot[4266]: time="2024-09-26T15:48:22Z" 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-26T15:48:21Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:24 volumio volumio[722]: info: Initializing connection to go-librespot Websocket Sep 26 15:48:24 volumio volumio[722]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 26 15:48:24 volumio volumio[722]: info: Sep 26 15:48:24 volumio volumio[722]: ---------------------------- MPD announces system playlist update Sep 26 15:48:24 volumio volumio[722]: info: Ignoring MPD Status Update Sep 26 15:48:24 volumio volumio[722]: info: Sep 26 15:48:24 volumio volumio[722]: ---------------------------- MPD announces state update: player Sep 26 15:48:24 volumio volumio[722]: info: ControllerMpd::getState Sep 26 15:48:24 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand status Sep 26 15:48:24 volumio volumio[722]: info: ------------------------------ 3ms Sep 26 15:48:24 volumio volumio[722]: info: sendMpdCommand status took 2 milliseconds Sep 26 15:48:24 volumio volumio[722]: verbose: ControllerMpd::parseState Sep 26 15:48:24 volumio volumio[722]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 26 15:48:24 volumio volumio[722]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 26 15:48:24 volumio volumio[722]: verbose: ControllerMpd::parseTrackInfo Sep 26 15:48:24 volumio volumio[722]: info: ControllerMpd::pushState Sep 26 15:48:24 volumio volumio[722]: info: CoreCommandRouter::servicePushState Sep 26 15:48:24 volumio volumio[722]: info: CorePlayQueue::getTrack 67 Sep 26 15:48:24 volumio volumio[722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"768 Kbps","isStreaming":false,"title":"Right here waiting for you (Richard Marx).flac","artist":null,"album":null,"uri":"NAS/music/10.Nhạc Đồng Quê Bất Hủ - Flac/Right here waiting for you (Richard Marx).flac","trackType":"flac"} Sep 26 15:48:24 volumio volumio[722]: verbose: CURRENT POSITION 67 Sep 26 15:48:24 volumio volumio[722]: info: CoreStateMachine::syncState stateService play Sep 26 15:48:24 volumio volumio[722]: info: CoreStateMachine::syncState currentStatus play Sep 26 15:48:24 volumio volumio[722]: info: Received an update from plugin. extracting info from payload Sep 26 15:48:24 volumio volumio[722]: info: CoreStateMachine::pushState Sep 26 15:48:24 volumio volumio[722]: info: CorePlayQueue::getTrack 67 Sep 26 15:48:24 volumio volumio[722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 26 15:48:24 volumio volumio[722]: info: CoreCommandRouter::volumioPushState Sep 26 15:48:24 volumio volumio[722]: info: CoreStateMachine::pushState Sep 26 15:48:24 volumio volumio[722]: info: CorePlayQueue::getTrack 67 Sep 26 15:48:24 volumio volumio[722]: info: CoreCommandRouter::volumioPushState Sep 26 15:48:24 volumio volumio[722]: info: ------------------------------ 26ms Sep 26 15:48:24 volumio volumio[722]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 15:48:24 volumio volumio[722]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 26 15:48:25 volumio volumio[722]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 15:48:25 volumio volumio[722]: Error: certificate is not yet valid Sep 26 15:48:25 volumio volumio[722]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Sep 26 15:48:25 volumio volumio[722]: at TLSSocket.emit (events.js:315:20) Sep 26 15:48:25 volumio volumio[722]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Sep 26 15:48:25 volumio volumio[722]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Sep 26 15:48:25 volumio volumio[722]: code: 'CERT_NOT_YET_VALID' Sep 26 15:48:25 volumio volumio[722]: } Sep 26 15:48:25 volumio volumio[722]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 26 15:48:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 26 15:48:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Sep 26 15:48:25 volumio systemd[1]: Stopped go-librespot Daemon. Sep 26 15:48:25 volumio systemd[1]: Started go-librespot Daemon. Sep 26 15:48:25 volumio go-librespot[4291]: Librespot-go daemon starting... Sep 26 15:48:25 volumio go-librespot[4291]: time="2024-09-26T15:48:25Z" level=info msg="generated new device id: 112959aa44ed02cbe59b0e43c25efdd955a24e8c" Sep 26 15:48:25 volumio ntpd[622]: Soliciting pool server 103.72.56.71 Sep 26 15:48:25 volumio go-librespot[4291]: time="2024-09-26T15:48:25Z" 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-26T15:48:25Z is before 2025-12-08T00:00:00Z" Sep 26 15:48:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 26 15:48:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 26 15:48:25 volumio sudo[4300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-26 15:47 Sep 26 15:48:25 volumio sudo[4300]: 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"