-- Logs begin at Fri 1970-01-02 16:44:17 +08, end at Fri 1970-01-02 19:09:07 +08. -- Jan 02 19:08:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35160. Jan 02 19:08:00 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:00 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:00 volumio go-librespot[26607]: go-librespot daemon starting... Jan 02 19:08:00 volumio go-librespot[26607]: time="1970-01-02T19:08:00+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:00 volumio go-librespot[26607]: time="1970-01-02T19:08:00+08:00" level=debug msg="app state loaded" Jan 02 19:08:00 volumio go-librespot[26607]: time="1970-01-02T19:08:00+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:01 volumio go-librespot[26607]: time="1970-01-02T19:08:01+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:01+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35161. Jan 02 19:08:04 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:04 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:04 volumio go-librespot[26616]: go-librespot daemon starting... Jan 02 19:08:04 volumio go-librespot[26616]: time="1970-01-02T19:08:04+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:04 volumio go-librespot[26616]: time="1970-01-02T19:08:04+08:00" level=debug msg="app state loaded" Jan 02 19:08:04 volumio go-librespot[26616]: time="1970-01-02T19:08:04+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:06 volumio go-librespot[26616]: time="1970-01-02T19:08:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:05+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35162. Jan 02 19:08:09 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:09 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:09 volumio go-librespot[26625]: go-librespot daemon starting... Jan 02 19:08:09 volumio go-librespot[26625]: time="1970-01-02T19:08:09+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:09 volumio go-librespot[26625]: time="1970-01-02T19:08:09+08:00" level=debug msg="app state loaded" Jan 02 19:08:09 volumio go-librespot[26625]: time="1970-01-02T19:08:09+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:10 volumio go-librespot[26625]: time="1970-01-02T19:08:10+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:10+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:13 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35163. Jan 02 19:08:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:13 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:13 volumio go-librespot[26650]: go-librespot daemon starting... Jan 02 19:08:13 volumio go-librespot[26650]: time="1970-01-02T19:08:13+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:13 volumio go-librespot[26650]: time="1970-01-02T19:08:13+08:00" level=debug msg="app state loaded" Jan 02 19:08:13 volumio go-librespot[26650]: time="1970-01-02T19:08:13+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:14 volumio go-librespot[26650]: time="1970-01-02T19:08:14+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:14+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:14 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:14 volumio volumio[25140]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.69 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jan 02 19:08:14 volumio volumio[25140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 02 19:08:14 volumio volumio[25140]: info: CoreCommandRouter::volumioGetState Jan 02 19:08:14 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35164. Jan 02 19:08:17 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:17 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:17 volumio go-librespot[26658]: go-librespot daemon starting... Jan 02 19:08:17 volumio go-librespot[26658]: time="1970-01-02T19:08:17+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:17 volumio go-librespot[26658]: time="1970-01-02T19:08:17+08:00" level=debug msg="app state loaded" Jan 02 19:08:17 volumio go-librespot[26658]: time="1970-01-02T19:08:17+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:17 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:17 volumio go-librespot[26658]: time="1970-01-02T19:08:17+08:00" level=debug msg="new websocket client" Jan 02 19:08:17 volumio volumio[25140]: info: Connection to go-librespot Websocket established Jan 02 19:08:17 volumio go-librespot[26658]: time="1970-01-02T19:08:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:17+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:17 volumio volumio[25140]: info: Connection to go-librespot Websocket closed Jan 02 19:08:20 volumio volumio[25140]: info: Getting Spotify volume Jan 02 19:08:20 volumio volumio[25140]: (node:25140) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:20 volumio volumio[25140]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 02 19:08:20 volumio volumio[25140]: (node:25140) 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: 1351) Jan 02 19:08:20 volumio volumio[25140]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 02 19:08:20 volumio volumio[25140]: info: CoreCommandRouter::volumioGetState Jan 02 19:08:20 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:20 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:20 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35165. Jan 02 19:08:20 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:20 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:20 volumio go-librespot[26680]: go-librespot daemon starting... Jan 02 19:08:20 volumio go-librespot[26680]: time="1970-01-02T19:08:20+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:20 volumio go-librespot[26680]: time="1970-01-02T19:08:20+08:00" level=debug msg="app state loaded" Jan 02 19:08:20 volumio go-librespot[26680]: time="1970-01-02T19:08:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:21 volumio go-librespot[26680]: time="1970-01-02T19:08:21+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:21+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:23 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:23 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35166. Jan 02 19:08:24 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:24 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:24 volumio go-librespot[26726]: go-librespot daemon starting... Jan 02 19:08:24 volumio go-librespot[26726]: time="1970-01-02T19:08:24+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:24 volumio go-librespot[26726]: time="1970-01-02T19:08:24+08:00" level=debug msg="app state loaded" Jan 02 19:08:24 volumio go-librespot[26726]: time="1970-01-02T19:08:24+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:24 volumio go-librespot[26726]: time="1970-01-02T19:08:24+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:24+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:26 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:26 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35167. Jan 02 19:08:27 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:27 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:27 volumio go-librespot[26735]: go-librespot daemon starting... Jan 02 19:08:27 volumio go-librespot[26735]: time="1970-01-02T19:08:27+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:27 volumio go-librespot[26735]: time="1970-01-02T19:08:27+08:00" level=debug msg="app state loaded" Jan 02 19:08:27 volumio go-librespot[26735]: time="1970-01-02T19:08:27+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:28 volumio go-librespot[26735]: time="1970-01-02T19:08:28+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:28+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:29 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:29 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35168. Jan 02 19:08:31 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:31 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:31 volumio go-librespot[26757]: go-librespot daemon starting... Jan 02 19:08:31 volumio go-librespot[26757]: time="1970-01-02T19:08:31+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:31 volumio go-librespot[26757]: time="1970-01-02T19:08:31+08:00" level=debug msg="app state loaded" Jan 02 19:08:31 volumio go-librespot[26757]: time="1970-01-02T19:08:31+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:31 volumio go-librespot[26757]: time="1970-01-02T19:08:31+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:31+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:32 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:32 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35169. Jan 02 19:08:34 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:34 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:34 volumio go-librespot[26768]: go-librespot daemon starting... Jan 02 19:08:34 volumio go-librespot[26768]: time="1970-01-02T19:08:34+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:34 volumio go-librespot[26768]: time="1970-01-02T19:08:34+08:00" level=debug msg="app state loaded" Jan 02 19:08:34 volumio go-librespot[26768]: time="1970-01-02T19:08:34+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:35 volumio go-librespot[26768]: time="1970-01-02T19:08:35+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:35+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:35 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:35 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35170. Jan 02 19:08:38 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:38 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:38 volumio go-librespot[26776]: go-librespot daemon starting... Jan 02 19:08:38 volumio go-librespot[26776]: time="1970-01-02T19:08:38+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:38 volumio go-librespot[26776]: time="1970-01-02T19:08:38+08:00" level=debug msg="app state loaded" Jan 02 19:08:38 volumio go-librespot[26776]: time="1970-01-02T19:08:38+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:38 volumio go-librespot[26776]: time="1970-01-02T19:08:38+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:38+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:38 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:38 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:41 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:41 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35171. Jan 02 19:08:41 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:41 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:41 volumio go-librespot[26798]: go-librespot daemon starting... Jan 02 19:08:41 volumio go-librespot[26798]: time="1970-01-02T19:08:41+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:41 volumio go-librespot[26798]: time="1970-01-02T19:08:41+08:00" level=debug msg="app state loaded" Jan 02 19:08:41 volumio go-librespot[26798]: time="1970-01-02T19:08:41+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:42 volumio go-librespot[26798]: time="1970-01-02T19:08:42+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:42+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:43 volumio volumio[25140]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.69 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::volumioGetVisibleSources Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::volumioGetState Jan 02 19:08:43 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::volumioGetQueue Jan 02 19:08:43 volumio volumio[25140]: info: CoreStateMachine::getQueue Jan 02 19:08:43 volumio volumio[25140]: info: CorePlayQueue::getQueue Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 02 19:08:43 volumio volumio[25140]: info: Received Get System Info Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 02 19:08:43 volumio volumio[25140]: info: Discovery: Getting this device information Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::volumioGetState Jan 02 19:08:43 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 02 19:08:43 volumio volumio[25140]: info: CoreCommandRouter::volumioGetState Jan 02 19:08:43 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:43 volumio volumio[25140]: info: Listing playlists Jan 02 19:08:44 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:44 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35172. Jan 02 19:08:45 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:45 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:45 volumio go-librespot[26807]: go-librespot daemon starting... Jan 02 19:08:45 volumio go-librespot[26807]: time="1970-01-02T19:08:45+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:45 volumio go-librespot[26807]: time="1970-01-02T19:08:45+08:00" level=debug msg="app state loaded" Jan 02 19:08:45 volumio go-librespot[26807]: time="1970-01-02T19:08:45+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:45 volumio go-librespot[26807]: time="1970-01-02T19:08:45+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:45+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:46 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 02 19:08:46 volumio volumio[25140]: info: CURURI: music-library/NAS/hp/Album Jan 02 19:08:46 volumio volumio[25140]: info: Preload queue cleared Jan 02 19:08:47 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:47 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35173. Jan 02 19:08:48 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:48 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:48 volumio go-librespot[26815]: go-librespot daemon starting... Jan 02 19:08:48 volumio go-librespot[26815]: time="1970-01-02T19:08:48+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:48 volumio go-librespot[26815]: time="1970-01-02T19:08:48+08:00" level=debug msg="app state loaded" Jan 02 19:08:48 volumio go-librespot[26815]: time="1970-01-02T19:08:48+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:49 volumio go-librespot[26815]: time="1970-01-02T19:08:49+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:49+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:50 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:08:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35174. Jan 02 19:08:52 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:52 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:52 volumio go-librespot[26838]: go-librespot daemon starting... Jan 02 19:08:52 volumio go-librespot[26838]: time="1970-01-02T19:08:52+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:52 volumio go-librespot[26838]: time="1970-01-02T19:08:52+08:00" level=debug msg="app state loaded" Jan 02 19:08:52 volumio go-librespot[26838]: time="1970-01-02T19:08:52+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:52 volumio go-librespot[26838]: time="1970-01-02T19:08:52+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:52+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35175. Jan 02 19:08:55 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:55 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:55 volumio go-librespot[26849]: go-librespot daemon starting... Jan 02 19:08:55 volumio go-librespot[26849]: time="1970-01-02T19:08:55+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:55 volumio go-librespot[26849]: time="1970-01-02T19:08:55+08:00" level=debug msg="app state loaded" Jan 02 19:08:55 volumio go-librespot[26849]: time="1970-01-02T19:08:55+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:56 volumio go-librespot[26849]: time="1970-01-02T19:08:56+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:56+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:08:59 volumio volumio[25140]: error: error Jan 02 19:08:59 volumio volumio[25140]: info: Preload queue cleared Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::ClearQueue Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::stop Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::stPlaybackTimer Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::updateTrackBlock Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrackBlock Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::serviceStop Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 97 Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::serviceStop Jan 02 19:08:59 volumio volumio[25140]: info: ControllerMpd::stop Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand stop Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::clearPlayQueue Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::saveQueue Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushQueue Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::addQueueItems Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::addQueueItems Jan 02 19:08:59 volumio volumio[25140]: info: Preload queue cleared Jan 02 19:08:59 volumio volumio[25140]: info: Adding Item to queue: music-library/NAS/hp/Album/Diana Krall Jan 02 19:08:59 volumio volumio[25140]: info: Exploding uri music-library/NAS/hp/Album/Diana Krall in service mpd Jan 02 19:08:59 volumio volumio[25140]: info: Jan 02 19:08:59 volumio volumio[25140]: ---------------------------- MPD announces state update: player Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FAlone%20Again%20Naturally.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Alone Again Naturally.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FCalifornia%20Dreamin.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/California Dreamin.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FDesperado.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Desperado.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FFeels%20Like%20Home.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Feels Like Home.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FI%20Can_t%20Tell%20You%20Why.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/I Can_t Tell You Why.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FI_m%20Not%20In%20Love.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/I_m Not In Love.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FIf%20I%20Take%20You%20Home%20Tonight.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/If I Take You Home Tonight.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FIn%20My%20Life%20-%20Diana%20Krall.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/In My Life - Diana Krall.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FOperator.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Operator.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Wallflower/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FSorry%20Seems%20To%20Be%20The%20Hardest%20Word.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Sorry Seems To Be The Hardest Word.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Krall/Audiophile%20Collections%20(Part%2026)/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FTemptation.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/Temptation.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Diana%20Kral/Audiophile%20Collections%20(Part%2034)/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FThe%20Look%20Of%20Love.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/The Look Of Love.flac Jan 02 19:08:59 volumio volumio[25140]: info: ALBUMART /albumart?cacheid=895&web=Ray%20Charles%3B%20Diana%20Krall/Genius%20Loves%20Company/extralarge&path=%2Fmnt%2FNAS%2Fhp%2FAlbum%2FDiana%20Krall%2FYou%20Dont%20Know%20Me.flac&metadata=false Jan 02 19:08:59 volumio volumio[25140]: info: URI /mnt/NAS/hp/Album/Diana Krall/You Dont Know Me.flac Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand stop took 106 milliseconds Jan 02 19:08:59 volumio volumio[25140]: info: ControllerMpd::getState Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand status Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushQueue Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::saveQueue Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::updateTrackBlock Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrackBlock Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPlay Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::play index 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::stop Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::play index undefined Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::startPlaybackTimer Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::clearAddPlayTracks NAS/hp/Album/Diana Krall/Alone Again Naturally.flac Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand stop Jan 02 19:08:59 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand status took 199 milliseconds Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand stop took 187 milliseconds Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::parseState Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand clear Jan 02 19:08:59 volumio volumio[25140]: info: Jan 02 19:08:59 volumio volumio[25140]: ---------------------------- MPD announces system playlist update Jan 02 19:08:59 volumio volumio[25140]: info: Ignoring MPD Status Update Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand playlistinfo took 182 milliseconds Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand clear took 182 milliseconds Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::parseTrackInfo Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand add "NAS/hp/Album/Diana Krall/Alone Again Naturally.flac" Jan 02 19:08:59 volumio volumio[25140]: info: ControllerMpd::pushState Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::servicePushState Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Xin Thời Gian Qua Mau - Hoàng Oanh.flac","artist":null,"album":"LP","uri":"NAS/hp/LP - ĐĨA THAN/Xin Thời Gian Qua Mau - Hoàng Oanh.flac","trackType":"flac"} Jan 02 19:08:59 volumio volumio[25140]: verbose: CURRENT POSITION 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::syncState stateService stop Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::syncState currentStatus stop Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:08:59 volumio volumio[25140]: info: No code Jan 02 19:08:59 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:08:59 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:08:59 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:08:59 volumio volumio[25140]: info: ------------------------------ 404ms Jan 02 19:08:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:08:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35176. Jan 02 19:08:59 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:08:59 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:08:59 volumio go-librespot[26858]: go-librespot daemon starting... Jan 02 19:08:59 volumio go-librespot[26858]: time="1970-01-02T19:08:59+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:08:59 volumio go-librespot[26858]: time="1970-01-02T19:08:59+08:00" level=debug msg="app state loaded" Jan 02 19:08:59 volumio go-librespot[26858]: time="1970-01-02T19:08:59+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:08:59 volumio volumio[25140]: error: updateQueue error: null Jan 02 19:08:59 volumio volumio[25140]: info: Jan 02 19:08:59 volumio volumio[25140]: ---------------------------- MPD announces system playlist update Jan 02 19:08:59 volumio volumio[25140]: info: Ignoring MPD Status Update Jan 02 19:08:59 volumio volumio[25140]: info: ------------------------------ 206ms Jan 02 19:08:59 volumio volumio[25140]: info: sendMpdCommand add "NAS/hp/Album/Diana Krall/Alone Again Naturally.flac" took 205 milliseconds Jan 02 19:08:59 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand play Jan 02 19:08:59 volumio go-librespot[26858]: time="1970-01-02T19:08:59+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:08:59+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:08:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:08:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:09:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:09:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35177. Jan 02 19:09:03 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:09:03 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:09:03 volumio go-librespot[26881]: go-librespot daemon starting... Jan 02 19:09:03 volumio go-librespot[26881]: time="1970-01-02T19:09:03+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:09:03 volumio go-librespot[26881]: time="1970-01-02T19:09:03+08:00" level=debug msg="app state loaded" Jan 02 19:09:03 volumio go-librespot[26881]: time="1970-01-02T19:09:03+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:09:03 volumio go-librespot[26881]: time="1970-01-02T19:09:03+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:09:03+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:09:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:09:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:09:05 volumio volumio[25140]: info: Jan 02 19:09:05 volumio volumio[25140]: ---------------------------- MPD announces state update: player Jan 02 19:09:05 volumio volumio[25140]: info: ------------------------------ 6108ms Jan 02 19:09:05 volumio volumio[25140]: info: sendMpdCommand play took 6106 milliseconds Jan 02 19:09:05 volumio volumio[25140]: info: ControllerMpd::getState Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand status Jan 02 19:09:05 volumio volumio[25140]: info: Initializing connection to go-librespot Websocket Jan 02 19:09:05 volumio volumio[25140]: info: Jan 02 19:09:05 volumio volumio[25140]: ---------------------------- MPD announces state update: player Jan 02 19:09:05 volumio volumio[25140]: info: ControllerMpd::getState Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand status Jan 02 19:09:05 volumio volumio[25140]: info: sendMpdCommand status took 108 milliseconds Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::parseState Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 02 19:09:05 volumio volumio[25140]: info: sendMpdCommand status took 27 milliseconds Jan 02 19:09:05 volumio volumio[25140]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::parseState Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::parseTrackInfo Jan 02 19:09:05 volumio volumio[25140]: info: ControllerMpd::pushState Jan 02 19:09:05 volumio volumio[25140]: info: CoreCommandRouter::servicePushState Jan 02 19:09:05 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:09:05 volumio volumio[25140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6841,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"Alone Again (Naturally)","artist":"Diana Krall","album":"Wallflower","uri":"NAS/hp/Album/Diana Krall/Alone Again Naturally.flac","trackType":"flac"} Jan 02 19:09:05 volumio volumio[25140]: verbose: CURRENT POSITION 0 Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::syncState stateService play Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::syncState currentStatus stop Jan 02 19:09:05 volumio volumio[25140]: info: ------------------------------ 139ms Jan 02 19:09:05 volumio volumio[25140]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 02 19:09:05 volumio volumio[25140]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 02 19:09:05 volumio volumio[25140]: verbose: ControllerMpd::parseTrackInfo Jan 02 19:09:05 volumio volumio[25140]: info: ControllerMpd::pushState Jan 02 19:09:05 volumio volumio[25140]: info: CoreCommandRouter::servicePushState Jan 02 19:09:05 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:09:05 volumio volumio[25140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6955,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"736 Kbps","isStreaming":false,"title":"Alone Again (Naturally)","artist":"Diana Krall","album":"Wallflower","uri":"NAS/hp/Album/Diana Krall/Alone Again Naturally.flac","trackType":"flac"} Jan 02 19:09:05 volumio volumio[25140]: verbose: CURRENT POSITION 0 Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::syncState stateService play Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::syncState currentStatus play Jan 02 19:09:05 volumio volumio[25140]: info: Received an update from plugin. extracting info from payload Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:09:05 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:09:05 volumio volumio[25140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 02 19:09:05 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:09:05 volumio volumio[25140]: info: CoreStateMachine::pushState Jan 02 19:09:05 volumio volumio[25140]: info: CorePlayQueue::getTrack 0 Jan 02 19:09:05 volumio volumio[25140]: info: CoreCommandRouter::volumioPushState Jan 02 19:09:05 volumio volumio[25140]: info: ------------------------------ 44ms Jan 02 19:09:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 02 19:09:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35178. Jan 02 19:09:06 volumio systemd[1]: Stopped go-librespot Daemon. Jan 02 19:09:06 volumio systemd[1]: Started go-librespot Daemon. Jan 02 19:09:06 volumio go-librespot[26890]: go-librespot daemon starting... Jan 02 19:09:06 volumio volumio[25140]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 02 19:09:06 volumio volumio[25140]: Error: certificate is not yet valid Jan 02 19:09:06 volumio volumio[25140]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 02 19:09:06 volumio volumio[25140]: at TLSSocket.emit (events.js:315:20) Jan 02 19:09:06 volumio volumio[25140]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 02 19:09:06 volumio volumio[25140]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 02 19:09:06 volumio volumio[25140]: code: 'CERT_NOT_YET_VALID' Jan 02 19:09:06 volumio volumio[25140]: } Jan 02 19:09:06 volumio volumio[25140]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 02 19:09:06 volumio go-librespot[26890]: time="1970-01-02T19:09:06+08:00" level=info msg="running go-librespot 0.2.0" Jan 02 19:09:06 volumio go-librespot[26890]: time="1970-01-02T19:09:06+08:00" level=debug msg="app state loaded" Jan 02 19:09:06 volumio go-librespot[26890]: time="1970-01-02T19:09:06+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 02 19:09:06 volumio go-librespot[26890]: time="1970-01-02T19:09:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 1970-01-02T19:09:06+08:00 is before 2025-01-06T00:00:00Z" Jan 02 19:09:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 02 19:09:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 02 19:09:07 volumio sudo[26908]: pam_unix(sudo:account): account volumio has password changed in future Jan 02 19:09:07 volumio sudo[26908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=1970-01-02 19:08 Jan 02 19:09:07 volumio sudo[26908]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"