-- Logs begin at Thu 2024-01-04 04:08:59 CET, end at Thu 2024-01-04 06:09:16 CET. -- Jan 04 06:08:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14993. Jan 04 06:08:00 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:00 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:00 volumio go-librespot[18327]: go-librespot daemon starting... Jan 04 06:08:00 volumio go-librespot[18327]: time="2024-01-04T06:08:00+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:00 volumio go-librespot[18327]: time="2024-01-04T06:08:00+01:00" level=debug msg="app state loaded" Jan 04 06:08:00 volumio go-librespot[18327]: time="2024-01-04T06:08:00+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:00 volumio go-librespot[18327]: time="2024-01-04T06:08:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:00 volumio go-librespot[18327]: time="2024-01-04T06:08:00+01:00" 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-01-04T06:08:00+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:01 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:01 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14994. Jan 04 06:08:04 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:04 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:04 volumio go-librespot[18352]: go-librespot daemon starting... Jan 04 06:08:04 volumio go-librespot[18352]: time="2024-01-04T06:08:04+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:04 volumio go-librespot[18352]: time="2024-01-04T06:08:04+01:00" level=debug msg="app state loaded" Jan 04 06:08:04 volumio go-librespot[18352]: time="2024-01-04T06:08:04+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:04 volumio go-librespot[18352]: time="2024-01-04T06:08:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:04 volumio go-librespot[18352]: time="2024-01-04T06:08:04+01:00" 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-01-04T06:08:04+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:04 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:04 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14995. Jan 04 06:08:07 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:07 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:07 volumio go-librespot[18359]: go-librespot daemon starting... Jan 04 06:08:07 volumio go-librespot[18359]: time="2024-01-04T06:08:07+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:07 volumio go-librespot[18359]: time="2024-01-04T06:08:07+01:00" level=debug msg="app state loaded" Jan 04 06:08:07 volumio go-librespot[18359]: time="2024-01-04T06:08:07+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:07 volumio go-librespot[18359]: time="2024-01-04T06:08:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:07 volumio go-librespot[18359]: time="2024-01-04T06:08:07+01:00" 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-01-04T06:08:07+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:07 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:07 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14996. Jan 04 06:08:10 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:10 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:10 volumio go-librespot[18368]: go-librespot daemon starting... Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" level=debug msg="app state loaded" Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:10 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" level=debug msg="new websocket client" Jan 04 06:08:10 volumio volumio[1061]: info: Connection to go-librespot Websocket established Jan 04 06:08:10 volumio go-librespot[18368]: time="2024-01-04T06:08:10+01:00" 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-01-04T06:08:10+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:10 volumio volumio[1061]: info: Connection to go-librespot Websocket closed Jan 04 06:08:13 volumio volumio[1061]: info: Getting Spotify volume Jan 04 06:08:13 volumio volumio[1061]: (node:1061) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:13 volumio volumio[1061]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 04 06:08:13 volumio volumio[1061]: (node:1061) 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: 114) Jan 04 06:08:13 volumio volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 04 06:08:13 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:13 volumio volumio[1061]: info: CoreCommandRouter::volumioGetState Jan 04 06:08:13 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:13 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14997. Jan 04 06:08:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:13 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:13 volumio go-librespot[18390]: go-librespot daemon starting... Jan 04 06:08:13 volumio go-librespot[18390]: time="2024-01-04T06:08:13+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:13 volumio go-librespot[18390]: time="2024-01-04T06:08:13+01:00" level=debug msg="app state loaded" Jan 04 06:08:13 volumio go-librespot[18390]: time="2024-01-04T06:08:13+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:13 volumio go-librespot[18390]: time="2024-01-04T06:08:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:13 volumio go-librespot[18390]: time="2024-01-04T06:08:13+01:00" 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-01-04T06:08:13+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:16 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:16 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14998. Jan 04 06:08:17 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:17 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:17 volumio go-librespot[18399]: go-librespot daemon starting... Jan 04 06:08:17 volumio go-librespot[18399]: time="2024-01-04T06:08:17+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:17 volumio go-librespot[18399]: time="2024-01-04T06:08:17+01:00" level=debug msg="app state loaded" Jan 04 06:08:17 volumio go-librespot[18399]: time="2024-01-04T06:08:17+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:17 volumio go-librespot[18399]: time="2024-01-04T06:08:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:17 volumio go-librespot[18399]: time="2024-01-04T06:08:17+01:00" 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-01-04T06:08:17+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:19 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:19 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14999. Jan 04 06:08:20 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:20 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:20 volumio go-librespot[18407]: go-librespot daemon starting... Jan 04 06:08:20 volumio go-librespot[18407]: time="2024-01-04T06:08:20+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:20 volumio go-librespot[18407]: time="2024-01-04T06:08:20+01:00" level=debug msg="app state loaded" Jan 04 06:08:20 volumio go-librespot[18407]: time="2024-01-04T06:08:20+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:20 volumio go-librespot[18407]: time="2024-01-04T06:08:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:20 volumio go-librespot[18407]: time="2024-01-04T06:08:20+01:00" 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-01-04T06:08:20+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:21 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:21 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:22 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:22 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:22 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15000. Jan 04 06:08:23 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:23 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:23 volumio go-librespot[18418]: go-librespot daemon starting... Jan 04 06:08:23 volumio go-librespot[18418]: time="2024-01-04T06:08:23+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:23 volumio go-librespot[18418]: time="2024-01-04T06:08:23+01:00" level=debug msg="app state loaded" Jan 04 06:08:23 volumio go-librespot[18418]: time="2024-01-04T06:08:23+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:23 volumio go-librespot[18418]: time="2024-01-04T06:08:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:23 volumio go-librespot[18418]: time="2024-01-04T06:08:23+01:00" 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-01-04T06:08:23+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:25 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:25 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:25 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:25 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::servicePushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:26 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Jan 04 06:08:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15001. Jan 04 06:08:26 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:26 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:26 volumio go-librespot[18440]: go-librespot daemon starting... Jan 04 06:08:26 volumio go-librespot[18440]: time="2024-01-04T06:08:26+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:26 volumio go-librespot[18440]: time="2024-01-04T06:08:26+01:00" level=debug msg="app state loaded" Jan 04 06:08:26 volumio go-librespot[18440]: time="2024-01-04T06:08:26+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:26 volumio go-librespot[18440]: time="2024-01-04T06:08:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:26 volumio go-librespot[18440]: time="2024-01-04T06:08:26+01:00" 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-01-04T06:08:26+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:28 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:28 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15002. Jan 04 06:08:30 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:30 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:30 volumio go-librespot[18448]: go-librespot daemon starting... Jan 04 06:08:30 volumio go-librespot[18448]: time="2024-01-04T06:08:30+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:30 volumio go-librespot[18448]: time="2024-01-04T06:08:30+01:00" level=debug msg="app state loaded" Jan 04 06:08:30 volumio go-librespot[18448]: time="2024-01-04T06:08:30+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:30 volumio go-librespot[18448]: time="2024-01-04T06:08:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:30 volumio go-librespot[18448]: time="2024-01-04T06:08:30+01:00" 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-01-04T06:08:30+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:31 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:31 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:32 volumio volumio[1061]: info: Updating volume from AirPlay: -10.62; 65% Jan 04 06:08:32 volumio volumio[1061]: info: VolumeController::SetAlsaVolume65 Jan 04 06:08:32 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:32 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:32 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: SPOTIFY VOLUME 58 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: VOLUMIO VOLUME 65 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 04 06:08:32 volumio volumio[1061]: info: Setting Spotify Volume from Volumio: 65 Jan 04 06:08:32 volumio volumio[1061]: info: Updating volume from AirPlay: -8.75; 71% Jan 04 06:08:32 volumio volumio[1061]: info: VolumeController::SetAlsaVolume71 Jan 04 06:08:32 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:32 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:32 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: SPOTIFY VOLUME 65 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: VOLUMIO VOLUME 71 Jan 04 06:08:32 volumio volumio[1061]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 04 06:08:32 volumio volumio[1061]: info: Setting Spotify Volume from Volumio: 71 Jan 04 06:08:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15003. Jan 04 06:08:33 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:33 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:33 volumio go-librespot[18457]: go-librespot daemon starting... Jan 04 06:08:33 volumio go-librespot[18457]: time="2024-01-04T06:08:33+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:33 volumio go-librespot[18457]: time="2024-01-04T06:08:33+01:00" level=debug msg="app state loaded" Jan 04 06:08:33 volumio go-librespot[18457]: time="2024-01-04T06:08:33+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:33 volumio go-librespot[18457]: time="2024-01-04T06:08:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:33 volumio go-librespot[18457]: time="2024-01-04T06:08:33+01:00" 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-01-04T06:08:33+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:34 volumio volumio[1061]: SPOTIFY: SETTING SPOTIFY VOLUME 71 Jan 04 06:08:34 volumio volumio[1061]: info: Sending Spotify command with payload to local API: /player/volume Jan 04 06:08:34 volumio volumio[1061]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:34 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:34 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15004. Jan 04 06:08:36 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:36 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:36 volumio go-librespot[18478]: go-librespot daemon starting... Jan 04 06:08:36 volumio go-librespot[18478]: time="2024-01-04T06:08:36+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:36 volumio go-librespot[18478]: time="2024-01-04T06:08:36+01:00" level=debug msg="app state loaded" Jan 04 06:08:36 volumio go-librespot[18478]: time="2024-01-04T06:08:36+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:36 volumio go-librespot[18478]: time="2024-01-04T06:08:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:36 volumio go-librespot[18478]: time="2024-01-04T06:08:36+01:00" 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-01-04T06:08:36+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:37 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:37 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15005. Jan 04 06:08:39 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:39 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:39 volumio go-librespot[18485]: go-librespot daemon starting... Jan 04 06:08:39 volumio go-librespot[18485]: time="2024-01-04T06:08:39+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:39 volumio go-librespot[18485]: time="2024-01-04T06:08:39+01:00" level=debug msg="app state loaded" Jan 04 06:08:39 volumio go-librespot[18485]: time="2024-01-04T06:08:39+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:39 volumio go-librespot[18485]: time="2024-01-04T06:08:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:39 volumio go-librespot[18485]: time="2024-01-04T06:08:39+01:00" 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-01-04T06:08:39+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:40 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:40 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15006. Jan 04 06:08:43 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:43 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:43 volumio go-librespot[18537]: go-librespot daemon starting... Jan 04 06:08:43 volumio go-librespot[18537]: time="2024-01-04T06:08:43+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:43 volumio go-librespot[18537]: time="2024-01-04T06:08:43+01:00" level=debug msg="app state loaded" Jan 04 06:08:43 volumio go-librespot[18537]: time="2024-01-04T06:08:43+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:43 volumio go-librespot[18537]: time="2024-01-04T06:08:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:43 volumio go-librespot[18537]: time="2024-01-04T06:08:43+01:00" 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-01-04T06:08:43+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:43 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:43 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15007. Jan 04 06:08:46 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:46 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:46 volumio go-librespot[18562]: go-librespot daemon starting... Jan 04 06:08:46 volumio go-librespot[18562]: time="2024-01-04T06:08:46+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:46 volumio go-librespot[18562]: time="2024-01-04T06:08:46+01:00" level=debug msg="app state loaded" Jan 04 06:08:46 volumio go-librespot[18562]: time="2024-01-04T06:08:46+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:46 volumio go-librespot[18562]: time="2024-01-04T06:08:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:46 volumio go-librespot[18562]: time="2024-01-04T06:08:46+01:00" 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-01-04T06:08:46+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:46 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:46 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:49 volumio thd[611]: {"time":1704335257619,"response":"random Success"}Executing trigger action: /usr/local/bin/volumio volume plus Jan 04 06:08:49 volumio thd[611]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 04 06:08:49 volumio thd[611]: Dload Upload Total Spent Left Speed Jan 04 06:08:49 volumio volumio[1061]: info: Jan 04 06:08:49 volumio volumio[1061]: ---------------------------- Client requests Volume + Jan 04 06:08:49 volumio volumio[1061]: info: VolumeController::SetAlsaVolume+ Jan 04 06:08:49 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:49 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:49 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:49 volumio thd[611]: [158B blob data] Jan 04 06:08:49 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 04 06:08:49 volumio volumio[1061]: SPOTIFY: SPOTIFY VOLUME 71 Jan 04 06:08:49 volumio volumio[1061]: SPOTIFY: VOLUMIO VOLUME 76 Jan 04 06:08:49 volumio volumio[1061]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 04 06:08:49 volumio volumio[1061]: info: Setting Spotify Volume from Volumio: 76 Jan 04 06:08:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15008. Jan 04 06:08:49 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:49 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:49 volumio go-librespot[18575]: go-librespot daemon starting... Jan 04 06:08:49 volumio go-librespot[18575]: time="2024-01-04T06:08:49+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:49 volumio go-librespot[18575]: time="2024-01-04T06:08:49+01:00" level=debug msg="app state loaded" Jan 04 06:08:49 volumio go-librespot[18575]: time="2024-01-04T06:08:49+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:49 volumio go-librespot[18575]: time="2024-01-04T06:08:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:49 volumio go-librespot[18575]: time="2024-01-04T06:08:49+01:00" 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-01-04T06:08:49+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:49 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:49 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:50 volumio thd[611]: {"time":1704344929072,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Jan 04 06:08:50 volumio thd[611]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 04 06:08:50 volumio thd[611]: Dload Upload Total Spent Left Speed Jan 04 06:08:50 volumio volumio[1061]: info: Jan 04 06:08:50 volumio volumio[1061]: ---------------------------- Client requests Volume + Jan 04 06:08:50 volumio volumio[1061]: info: VolumeController::SetAlsaVolume+ Jan 04 06:08:50 volumio volumio[1061]: info: CoreStateMachine::pushState Jan 04 06:08:50 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 04 06:08:50 volumio volumio[1061]: info: CoreCommandRouter::volumioPushState Jan 04 06:08:50 volumio thd[611]: [158B blob data] Jan 04 06:08:50 volumio volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81 Jan 04 06:08:50 volumio volumio[1061]: SPOTIFY: SPOTIFY VOLUME 76 Jan 04 06:08:50 volumio volumio[1061]: SPOTIFY: VOLUMIO VOLUME 81 Jan 04 06:08:50 volumio volumio[1061]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 04 06:08:50 volumio volumio[1061]: info: Setting Spotify Volume from Volumio: 81 Jan 04 06:08:51 volumio thd[611]: {"time":1704344930327,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio toggle Jan 04 06:08:51 volumio thd[611]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 04 06:08:51 volumio thd[611]: Dload Upload Total Spent Left Speed Jan 04 06:08:51 volumio volumio[1061]: info: Jan 04 06:08:51 volumio volumio[1061]: ---------------------------- Client requests Volumio toggle Jan 04 06:08:51 volumio volumio[1061]: info: CoreCommandRouter::volumioToggle Jan 04 06:08:51 volumio volumio[1061]: info: CoreStateMachine::pause Jan 04 06:08:51 volumio volumio[1061]: info: CoreStateMachine::stPlaybackTimer Jan 04 06:08:51 volumio volumio[1061]: info: CoreStateMachine::servicePause Jan 04 06:08:51 volumio volumio[1061]: info: CoreCommandRouter::servicePause Jan 04 06:08:51 volumio volumio[1061]: info: Airplay Pause with DBUS Call Jan 04 06:08:51 volumio thd[611]: [158B blob data] Jan 04 06:08:51 volumio volumio[1061]: info: Error Airplay DBUS Call Pause: Error: Command failed: /usr/bin/dbus-send --system --print-reply --type=method_call --dest=org.gnome.ShairportSync '/org/gnome/ShairportSync' org.gnome.ShairportSync.RemoteControl.Pause Jan 04 06:08:51 volumio volumio[1061]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jan 04 06:08:51 volumio volumio[1061]: SPOTIFY: SETTING SPOTIFY VOLUME 81 Jan 04 06:08:51 volumio volumio[1061]: info: Sending Spotify command with payload to local API: /player/volume Jan 04 06:08:51 volumio volumio[1061]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:52 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:52 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15009. Jan 04 06:08:52 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:52 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:52 volumio go-librespot[18630]: go-librespot daemon starting... Jan 04 06:08:52 volumio go-librespot[18630]: time="2024-01-04T06:08:52+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:52 volumio go-librespot[18630]: time="2024-01-04T06:08:52+01:00" level=debug msg="app state loaded" Jan 04 06:08:52 volumio go-librespot[18630]: time="2024-01-04T06:08:52+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:52 volumio go-librespot[18630]: time="2024-01-04T06:08:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:52 volumio go-librespot[18630]: time="2024-01-04T06:08:52+01:00" 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-01-04T06:08:52+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:55 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:55 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15010. Jan 04 06:08:56 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:56 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:56 volumio go-librespot[18674]: go-librespot daemon starting... Jan 04 06:08:56 volumio go-librespot[18674]: time="2024-01-04T06:08:56+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:56 volumio go-librespot[18674]: time="2024-01-04T06:08:56+01:00" level=debug msg="app state loaded" Jan 04 06:08:56 volumio go-librespot[18674]: time="2024-01-04T06:08:56+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:56 volumio go-librespot[18674]: time="2024-01-04T06:08:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:56 volumio go-librespot[18674]: time="2024-01-04T06:08:56+01:00" 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-01-04T06:08:56+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:08:58 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:08:58 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:08:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:08:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15011. Jan 04 06:08:59 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:08:59 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:08:59 volumio go-librespot[18692]: go-librespot daemon starting... Jan 04 06:08:59 volumio go-librespot[18692]: time="2024-01-04T06:08:59+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:08:59 volumio go-librespot[18692]: time="2024-01-04T06:08:59+01:00" level=debug msg="app state loaded" Jan 04 06:08:59 volumio go-librespot[18692]: time="2024-01-04T06:08:59+01:00" level=debug msg="stored credentials not found" Jan 04 06:08:59 volumio go-librespot[18692]: time="2024-01-04T06:08:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:08:59 volumio go-librespot[18692]: time="2024-01-04T06:08:59+01:00" 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-01-04T06:08:59+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:08:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:08:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:01 volumio thd[611]: {"time":1704344931355,"response":"toggle Success"}Executing trigger action: /usr/local/bin/volumio random Jan 04 06:09:01 volumio thd[611]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 04 06:09:01 volumio thd[611]: Dload Upload Total Spent Left Speed Jan 04 06:09:01 volumio volumio[1061]: info: Jan 04 06:09:01 volumio volumio[1061]: ---------------------------- Client requests Random undefined Jan 04 06:09:01 volumio volumio[1061]: info: CoreCommandRouter::writePlayerControls Jan 04 06:09:01 volumio volumio[1061]: info: CoreStateMachine::setRandom true Jan 04 06:09:01 volumio volumio[1061]: info: WARNING: No random method for plugin airplay_emulation Jan 04 06:09:01 volumio thd[611]: [158B blob data] Jan 04 06:09:01 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:09:01 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:09:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:09:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15012. Jan 04 06:09:02 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:09:02 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:09:02 volumio go-librespot[18725]: go-librespot daemon starting... Jan 04 06:09:02 volumio go-librespot[18725]: time="2024-01-04T06:09:02+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:09:02 volumio go-librespot[18725]: time="2024-01-04T06:09:02+01:00" level=debug msg="app state loaded" Jan 04 06:09:02 volumio go-librespot[18725]: time="2024-01-04T06:09:02+01:00" level=debug msg="stored credentials not found" Jan 04 06:09:02 volumio go-librespot[18725]: time="2024-01-04T06:09:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:09:02 volumio go-librespot[18725]: time="2024-01-04T06:09:02+01:00" 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-01-04T06:09:02+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:09:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:09:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:04 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:09:04 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:09:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:09:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15013. Jan 04 06:09:05 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:09:05 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:09:05 volumio go-librespot[18781]: go-librespot daemon starting... Jan 04 06:09:05 volumio go-librespot[18781]: time="2024-01-04T06:09:05+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:09:05 volumio go-librespot[18781]: time="2024-01-04T06:09:05+01:00" level=debug msg="app state loaded" Jan 04 06:09:05 volumio go-librespot[18781]: time="2024-01-04T06:09:05+01:00" level=debug msg="stored credentials not found" Jan 04 06:09:05 volumio go-librespot[18781]: time="2024-01-04T06:09:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:09:05 volumio go-librespot[18781]: time="2024-01-04T06:09:05+01:00" 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-01-04T06:09:05+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:09:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:09:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:07 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:09:07 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:09:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:09:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15014. Jan 04 06:09:09 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:09:09 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:09:09 volumio go-librespot[18820]: go-librespot daemon starting... Jan 04 06:09:09 volumio go-librespot[18820]: time="2024-01-04T06:09:09+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:09:09 volumio go-librespot[18820]: time="2024-01-04T06:09:09+01:00" level=debug msg="app state loaded" Jan 04 06:09:09 volumio go-librespot[18820]: time="2024-01-04T06:09:09+01:00" level=debug msg="stored credentials not found" Jan 04 06:09:09 volumio go-librespot[18820]: time="2024-01-04T06:09:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:09:09 volumio go-librespot[18820]: time="2024-01-04T06:09:09+01:00" 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-01-04T06:09:09+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:09:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:09:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:10 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:09:10 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:09:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:09:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15015. Jan 04 06:09:12 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:09:12 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:09:12 volumio go-librespot[18858]: go-librespot daemon starting... Jan 04 06:09:12 volumio go-librespot[18858]: time="2024-01-04T06:09:12+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:09:12 volumio go-librespot[18858]: time="2024-01-04T06:09:12+01:00" level=debug msg="app state loaded" Jan 04 06:09:12 volumio go-librespot[18858]: time="2024-01-04T06:09:12+01:00" level=debug msg="stored credentials not found" Jan 04 06:09:12 volumio go-librespot[18858]: time="2024-01-04T06:09:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:09:12 volumio go-librespot[18858]: time="2024-01-04T06:09:12+01:00" 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-01-04T06:09:12+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:09:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:09:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:13 volumio volumio[1061]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 04 06:09:13 volumio volumio[1061]: info: Initializing connection to go-librespot Websocket Jan 04 06:09:13 volumio volumio[1061]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 04 06:09:14 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 04 06:09:14 volumio volumio[1061]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::volumioGetVisibleSources Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::volumioGetState Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 04 06:09:15 volumio volumio[1061]: info: Received Get System Info Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 04 06:09:15 volumio volumio[1061]: info: Discovery: Getting this device information Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::volumioGetState Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::volumioGetState Jan 04 06:09:15 volumio volumio[1061]: info: Listing playlists Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 04 06:09:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 04 06:09:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15016. Jan 04 06:09:15 volumio systemd[1]: Stopped go-librespot Daemon. Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 04 06:09:15 volumio volumio[1061]: info: FusionDsp - Rock-FusionDsp Jan 04 06:09:15 volumio systemd[1]: Started go-librespot Daemon. Jan 04 06:09:15 volumio go-librespot[18884]: go-librespot daemon starting... Jan 04 06:09:15 volumio go-librespot[18884]: time="2024-01-04T06:09:15+01:00" level=info msg="running go-librespot 0.2.0" Jan 04 06:09:15 volumio go-librespot[18884]: time="2024-01-04T06:09:15+01:00" level=debug msg="app state loaded" Jan 04 06:09:15 volumio go-librespot[18884]: time="2024-01-04T06:09:15+01:00" level=debug msg="stored credentials not found" Jan 04 06:09:15 volumio go-librespot[18884]: time="2024-01-04T06:09:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 04 06:09:15 volumio volumio[1061]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jan 04 06:09:15 volumio go-librespot[18884]: time="2024-01-04T06:09:15+01:00" 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-01-04T06:09:15+01:00 is before 2025-01-06T00:00:00Z" Jan 04 06:09:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 04 06:09:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 04 06:09:15 volumio volumio[1061]: info: CoreCommandRouter::volumioGetState Jan 04 06:09:15 volumio volumio[1061]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 04 06:09:15 volumio volumio[1061]: Error: certificate is not yet valid Jan 04 06:09:15 volumio volumio[1061]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 04 06:09:15 volumio volumio[1061]: at TLSSocket.emit (events.js:315:20) Jan 04 06:09:15 volumio volumio[1061]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 04 06:09:15 volumio volumio[1061]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 04 06:09:15 volumio volumio[1061]: code: 'CERT_NOT_YET_VALID' Jan 04 06:09:15 volumio volumio[1061]: } Jan 04 06:09:15 volumio volumio[1061]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 04 06:09:16 volumio sudo[18901]: pam_unix(sudo:account): account volumio has password changed in future Jan 04 06:09:16 volumio sudo[18901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-01-04 06:08 Jan 04 06:09:16 volumio sudo[18901]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"