-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Thu 2024-08-15 17:18:45 AEST. -- Aug 15 17:17:00 minidsp-shd ntpd[809]: Soliciting pool server 129.250.35.250 Aug 15 17:17:01 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:01 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:01 minidsp-shd ntpd[809]: Soliciting pool server 194.195.249.28 Aug 15 17:17:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 398. Aug 15 17:17:02 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:02 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:02 minidsp-shd go-librespot[5381]: Librespot-go daemon starting... Aug 15 17:17:02 minidsp-shd go-librespot[5381]: time="2024-08-15T17:17:02+10:00" level=info msg="generated new device id: 6458efbbb23e38b8a77ace7e9369c3445c27ac9a" Aug 15 17:17:02 minidsp-shd go-librespot[5381]: time="2024-08-15T17:17:02+10: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-08-15T17:17:02+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:04 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:04 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 399. Aug 15 17:17:05 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:05 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:05 minidsp-shd go-librespot[5389]: Librespot-go daemon starting... Aug 15 17:17:05 minidsp-shd go-librespot[5389]: time="2024-08-15T17:17:05+10:00" level=info msg="generated new device id: 29eb271a5c69b6d9a99cc31c037f8fd1bddbfac7" Aug 15 17:17:05 minidsp-shd go-librespot[5389]: time="2024-08-15T17:17:05+10: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-08-15T17:17:05+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:07 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:07 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 400. Aug 15 17:17:08 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:08 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:08 minidsp-shd go-librespot[5397]: Librespot-go daemon starting... Aug 15 17:17:08 minidsp-shd go-librespot[5397]: time="2024-08-15T17:17:08+10:00" level=info msg="generated new device id: 9f151b8d38ded2967d2c4da3c2404a1b92ad41fb" Aug 15 17:17:09 minidsp-shd go-librespot[5397]: time="2024-08-15T17:17:09+10: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-08-15T17:17:08+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:10 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:10 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 401. Aug 15 17:17:12 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:12 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:12 minidsp-shd go-librespot[5405]: Librespot-go daemon starting... Aug 15 17:17:12 minidsp-shd go-librespot[5405]: time="2024-08-15T17:17:12+10:00" level=info msg="generated new device id: db2c883e6c0a8b0524dbda05609e54aea0a916a6" Aug 15 17:17:12 minidsp-shd go-librespot[5405]: time="2024-08-15T17:17:12+10: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-08-15T17:17:12+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:13 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:13 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 402. Aug 15 17:17:15 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:15 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:15 minidsp-shd go-librespot[5415]: Librespot-go daemon starting... Aug 15 17:17:15 minidsp-shd go-librespot[5415]: time="2024-08-15T17:17:15+10:00" level=info msg="generated new device id: 94b049b5634c0b98f8e0b35fc4c462014778f29b" Aug 15 17:17:15 minidsp-shd go-librespot[5415]: time="2024-08-15T17:17:15+10: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-08-15T17:17:15+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:16 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:16 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:17 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":57,"dbVolume":-54.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 56 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 57 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:17:17 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":58,"dbVolume":-54,"mute":false,"disableVolumeControl":false} Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 56 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 58 Aug 15 17:17:17 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:17:17 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 58 Aug 15 17:17:17 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":58,"dbVolume":-53.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:17 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:17 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 15 17:17:18 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":58,"dbVolume":-53,"mute":false,"disableVolumeControl":false} Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 15 17:17:18 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":59,"dbVolume":-52.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 58 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 59 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:17:18 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":59,"dbVolume":-52,"mute":false,"disableVolumeControl":false} Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 58 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 59 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:17:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 403. Aug 15 17:17:18 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:18 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":60,"dbVolume":-51.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:18 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:18 minidsp-shd go-librespot[5423]: Librespot-go daemon starting... Aug 15 17:17:18 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:18 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:18 minidsp-shd go-librespot[5423]: time="2024-08-15T17:17:18+10:00" level=info msg="generated new device id: 081b2f32664655934645a3856a838f9af7ce813f" Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 58 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 60 Aug 15 17:17:18 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:17:18 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 60 Aug 15 17:17:18 minidsp-shd go-librespot[5423]: time="2024-08-15T17:17:18+10: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-08-15T17:17:18+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:19 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:19 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:20 minidsp-shd volumio[662]: SPOTIFY: SETTING SPOTIFY VOLUME 60 Aug 15 17:17:20 minidsp-shd volumio[662]: info: Sending Spotify command with payload to local API: /player/volume Aug 15 17:17:20 minidsp-shd volumio[662]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 404. Aug 15 17:17:21 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:21 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:21 minidsp-shd go-librespot[5431]: Librespot-go daemon starting... Aug 15 17:17:21 minidsp-shd go-librespot[5431]: time="2024-08-15T17:17:21+10:00" level=info msg="generated new device id: 2798fd7d222a7beda4f0b6ed419e7b1dba2240fc" Aug 15 17:17:22 minidsp-shd go-librespot[5431]: time="2024-08-15T17:17:22+10: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-08-15T17:17:21+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:22 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:22 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 405. Aug 15 17:17:25 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:25 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:25 minidsp-shd go-librespot[5440]: Librespot-go daemon starting... Aug 15 17:17:25 minidsp-shd go-librespot[5440]: time="2024-08-15T17:17:25+10:00" level=info msg="generated new device id: d2873972f1e9c450c7821837bba63b99a6a4b070" Aug 15 17:17:25 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:25 minidsp-shd go-librespot[5440]: time="2024-08-15T17:17:25+10:00" level=debug msg="new websocket client" Aug 15 17:17:25 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket established Aug 15 17:17:25 minidsp-shd go-librespot[5440]: time="2024-08-15T17:17:25+10: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-08-15T17:17:25+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:25 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket closed Aug 15 17:17:28 minidsp-shd volumio[662]: info: Getting Spotify volume Aug 15 17:17:28 minidsp-shd volumio[662]: (node:662) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:28 minidsp-shd volumio[662]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 15 17:17:28 minidsp-shd volumio[662]: (node:662) 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: 32) Aug 15 17:17:28 minidsp-shd volumio[662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Aug 15 17:17:28 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetState Aug 15 17:17:28 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 15 17:17:28 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:28 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 406. Aug 15 17:17:28 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:28 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:28 minidsp-shd go-librespot[5448]: Librespot-go daemon starting... Aug 15 17:17:28 minidsp-shd go-librespot[5448]: time="2024-08-15T17:17:28+10:00" level=info msg="generated new device id: c142b666927df538f28becdc3e56e47872d8b73c" Aug 15 17:17:28 minidsp-shd go-librespot[5448]: time="2024-08-15T17:17:28+10: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-08-15T17:17:28+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:31 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:31 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 407. Aug 15 17:17:31 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:31 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:31 minidsp-shd go-librespot[5456]: Librespot-go daemon starting... Aug 15 17:17:31 minidsp-shd go-librespot[5456]: time="2024-08-15T17:17:31+10:00" level=info msg="generated new device id: 5164b3bdedcf835119d6f4fd12cb79f8deddb53c" Aug 15 17:17:31 minidsp-shd go-librespot[5456]: time="2024-08-15T17:17:31+10: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-08-15T17:17:31+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:34 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:34 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 408. Aug 15 17:17:34 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:34 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:34 minidsp-shd go-librespot[5464]: Librespot-go daemon starting... Aug 15 17:17:34 minidsp-shd go-librespot[5464]: time="2024-08-15T17:17:34+10:00" level=info msg="generated new device id: f1e6306739b09e94ce06ecb5b257026987c1ae9e" Aug 15 17:17:35 minidsp-shd go-librespot[5464]: time="2024-08-15T17:17:35+10: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-08-15T17:17:34+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:35 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:35 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:37 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:37 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 409. Aug 15 17:17:38 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:38 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:38 minidsp-shd go-librespot[5472]: Librespot-go daemon starting... Aug 15 17:17:38 minidsp-shd go-librespot[5472]: time="2024-08-15T17:17:38+10:00" level=info msg="generated new device id: 5b2d70cd7c3186b0900a0d516519dc66604312cd" Aug 15 17:17:38 minidsp-shd go-librespot[5472]: time="2024-08-15T17:17:38+10: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-08-15T17:17:38+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:40 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:40 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 410. Aug 15 17:17:41 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:41 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:41 minidsp-shd go-librespot[5480]: Librespot-go daemon starting... Aug 15 17:17:41 minidsp-shd go-librespot[5480]: time="2024-08-15T17:17:41+10:00" level=info msg="generated new device id: f237fb42da1a48ae5635afe4bfd25b268122d536" Aug 15 17:17:41 minidsp-shd go-librespot[5480]: time="2024-08-15T17:17:41+10: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-08-15T17:17:41+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:43 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:43 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 411. Aug 15 17:17:44 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:44 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:44 minidsp-shd go-librespot[5489]: Librespot-go daemon starting... Aug 15 17:17:44 minidsp-shd go-librespot[5489]: time="2024-08-15T17:17:44+10:00" level=info msg="generated new device id: b897ece3d7c93e0dace0b76b5e27c7f37a6a5250" Aug 15 17:17:44 minidsp-shd go-librespot[5489]: time="2024-08-15T17:17:44+10: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-08-15T17:17:44+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:46 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:46 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:46 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":60,"dbVolume":-51,"mute":false,"disableVolumeControl":false} Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 15 17:17:46 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":60,"dbVolume":-50.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 15 17:17:46 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":61,"dbVolume":-50,"mute":false,"disableVolumeControl":false} Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:46 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:46 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:17:46 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:17:47 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":61,"dbVolume":-49.5,"mute":false,"disableVolumeControl":false} Aug 15 17:17:47 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:17:47 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:17:47 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:17:47 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:17:47 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:17:47 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:17:47 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:17:47 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:17:47 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:17:47 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:17:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 412. Aug 15 17:17:47 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:47 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:47 minidsp-shd go-librespot[5497]: Librespot-go daemon starting... Aug 15 17:17:47 minidsp-shd go-librespot[5497]: time="2024-08-15T17:17:47+10:00" level=info msg="generated new device id: 59b319b4c06878811ad89504cc124af75de9040d" Aug 15 17:17:48 minidsp-shd go-librespot[5497]: time="2024-08-15T17:17:48+10: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-08-15T17:17:47+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:48 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:48 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:49 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:49 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 413. Aug 15 17:17:51 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:51 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:51 minidsp-shd go-librespot[5543]: Librespot-go daemon starting... Aug 15 17:17:51 minidsp-shd go-librespot[5543]: time="2024-08-15T17:17:51+10:00" level=info msg="generated new device id: a5b8f852a48acda3f4c50e48d3178c411d38c468" Aug 15 17:17:51 minidsp-shd go-librespot[5543]: time="2024-08-15T17:17:51+10: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-08-15T17:17:51+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:52 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:52 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 414. Aug 15 17:17:54 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:54 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:54 minidsp-shd go-librespot[5551]: Librespot-go daemon starting... Aug 15 17:17:54 minidsp-shd go-librespot[5551]: time="2024-08-15T17:17:54+10:00" level=info msg="generated new device id: 5ccc1c9c1c05b7578d6d59aefba827c4d1e7a173" Aug 15 17:17:54 minidsp-shd go-librespot[5551]: time="2024-08-15T17:17:54+10: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-08-15T17:17:54+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:55 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:55 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:57 minidsp-shd ntpd[809]: Soliciting pool server 2401:c080:2000:1271:5400:4ff:fe0b:be11 Aug 15 17:17:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:17:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 415. Aug 15 17:17:57 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:17:57 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:17:57 minidsp-shd go-librespot[5560]: Librespot-go daemon starting... Aug 15 17:17:57 minidsp-shd go-librespot[5560]: time="2024-08-15T17:17:57+10:00" level=info msg="generated new device id: ede9b3f1ec961ec106e57472eb48ee3d42e10175" Aug 15 17:17:57 minidsp-shd go-librespot[5560]: time="2024-08-15T17:17:57+10: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-08-15T17:17:57+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:17:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:17:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:17:58 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:17:58 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:17:58 minidsp-shd ntpd[809]: Soliciting pool server 162.159.200.123 Aug 15 17:18:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 416. Aug 15 17:18:00 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:00 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:00 minidsp-shd go-librespot[5569]: Librespot-go daemon starting... Aug 15 17:18:00 minidsp-shd go-librespot[5569]: time="2024-08-15T17:18:00+10:00" level=info msg="generated new device id: d8da040575242ac8351bb7db47e59bdd245b2c13" Aug 15 17:18:01 minidsp-shd go-librespot[5569]: time="2024-08-15T17:18:01+10: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-08-15T17:18:00+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:01 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:01 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:03 minidsp-shd volumio[662]: info: Airplay started streaming, receiving metadatas Aug 15 17:18:03 minidsp-shd volumio[662]: verbose: UNSET VOLATILE: Service: inputs Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:03 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:03 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:03 minidsp-shd volumio[662]: info: Airplay started streaming, receiving metadatas Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrack 0 Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioStop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::stop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::stPlaybackTimer Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::updateTrackBlock Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrackBlock Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrack 0 Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:03 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:03 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::serviceStop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrack 0 Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::serviceStop Aug 15 17:18:03 minidsp-shd volumio[662]: info: ControllerMpd::stop Aug 15 17:18:03 minidsp-shd volumio[662]: verbose: ControllerMpd::sendMpdCommand stop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 15 17:18:03 minidsp-shd volumio[662]: info: Airplay started streaming, receiving metadatas Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrack 0 Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioStop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::stop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 15 17:18:03 minidsp-shd shairport-sync[1135]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 15 17:18:03 minidsp-shd shairport-sync[1135]: Dload Upload Total Spent Left Speed Aug 15 17:18:03 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:03 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:03 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:03 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:03 minidsp-shd volumio[662]: info: sendMpdCommand stop took 30 milliseconds Aug 15 17:18:03 minidsp-shd volumio[662]: info: Aug 15 17:18:03 minidsp-shd volumio[662]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 15 17:18:03 minidsp-shd volumio[662]: info: Airplay playback start Aug 15 17:18:03 minidsp-shd volumio[662]: info: CorePlayQueue::getTrack 0 Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioStop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::stop Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 15 17:18:03 minidsp-shd volumio[662]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 15 17:18:03 minidsp-shd shairport-sync[1135]: [158B blob data] Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 417. Aug 15 17:18:04 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:04 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:04 minidsp-shd go-librespot[5587]: Librespot-go daemon starting... Aug 15 17:18:04 minidsp-shd go-librespot[5587]: time="2024-08-15T17:18:04+10:00" level=info msg="generated new device id: eaf962680d033e46a26e5c79f8ca15a7be69e259" Aug 15 17:18:04 minidsp-shd go-librespot[5587]: time="2024-08-15T17:18:04+10: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-08-15T17:18:04+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:04 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:04 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:04 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:04 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd ntpd[809]: Soliciting pool server 14.202.65.230 Aug 15 17:18:05 minidsp-shd ntpd[809]: Soliciting pool server 162.159.200.1 Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::servicePushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:05 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:05 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 418. Aug 15 17:18:07 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:07 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:07 minidsp-shd go-librespot[5595]: Librespot-go daemon starting... Aug 15 17:18:07 minidsp-shd go-librespot[5595]: time="2024-08-15T17:18:07+10:00" level=info msg="generated new device id: 528226bf81d545f4b31c0feb9180eb9b5f4e3f12" Aug 15 17:18:07 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:07 minidsp-shd go-librespot[5595]: time="2024-08-15T17:18:07+10:00" level=debug msg="new websocket client" Aug 15 17:18:07 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket established Aug 15 17:18:07 minidsp-shd go-librespot[5595]: time="2024-08-15T17:18:07+10: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-08-15T17:18:07+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:07 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket closed Aug 15 17:18:10 minidsp-shd volumio[662]: info: Getting Spotify volume Aug 15 17:18:10 minidsp-shd volumio[662]: (node:662) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:10 minidsp-shd volumio[662]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 15 17:18:10 minidsp-shd volumio[662]: (node:662) 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: 33) Aug 15 17:18:10 minidsp-shd volumio[662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Aug 15 17:18:10 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetState Aug 15 17:18:10 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Aug 15 17:18:10 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:10 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 61 Aug 15 17:18:10 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:10 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 419. Aug 15 17:18:10 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:10 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:10 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:10 minidsp-shd go-librespot[5603]: Librespot-go daemon starting... Aug 15 17:18:10 minidsp-shd go-librespot[5603]: time="2024-08-15T17:18:10+10:00" level=info msg="generated new device id: 9667ebbf41edbda7db36b07a43992137edf3177b" Aug 15 17:18:10 minidsp-shd go-librespot[5603]: time="2024-08-15T17:18:10+10: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-08-15T17:18:10+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:13 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:13 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 420. Aug 15 17:18:14 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:14 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:14 minidsp-shd go-librespot[5611]: Librespot-go daemon starting... Aug 15 17:18:14 minidsp-shd go-librespot[5611]: time="2024-08-15T17:18:14+10:00" level=info msg="generated new device id: 0f8c9394631d62bc3014eeda3f6ec67a67896817" Aug 15 17:18:14 minidsp-shd go-librespot[5611]: time="2024-08-15T17:18:14+10: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-08-15T17:18:14+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:16 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:16 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 421. Aug 15 17:18:17 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:17 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:17 minidsp-shd go-librespot[5619]: Librespot-go daemon starting... Aug 15 17:18:17 minidsp-shd go-librespot[5619]: time="2024-08-15T17:18:17+10:00" level=info msg="generated new device id: 44cba3266ad5dc1b0ed0a6059e2f8e708a6c2754" Aug 15 17:18:17 minidsp-shd go-librespot[5619]: time="2024-08-15T17:18:17+10: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-08-15T17:18:17+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:19 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:19 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 422. Aug 15 17:18:20 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:20 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:20 minidsp-shd go-librespot[5629]: Librespot-go daemon starting... Aug 15 17:18:20 minidsp-shd go-librespot[5629]: time="2024-08-15T17:18:20+10:00" level=info msg="generated new device id: 791af8344bd56af950b75af18b7e8ad099a3a7f5" Aug 15 17:18:20 minidsp-shd go-librespot[5629]: time="2024-08-15T17:18:20+10: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-08-15T17:18:20+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:22 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:22 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 423. Aug 15 17:18:23 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:23 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:23 minidsp-shd go-librespot[5638]: Librespot-go daemon starting... Aug 15 17:18:23 minidsp-shd go-librespot[5638]: time="2024-08-15T17:18:23+10:00" level=info msg="generated new device id: b6940e59ff79032dcd3b739a889c94c4537bc8bd" Aug 15 17:18:24 minidsp-shd go-librespot[5638]: time="2024-08-15T17:18:24+10: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-08-15T17:18:23+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:24 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:24 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:25 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:25 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:27 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:27 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 424. Aug 15 17:18:27 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:27 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:27 minidsp-shd go-librespot[5646]: Librespot-go daemon starting... Aug 15 17:18:27 minidsp-shd go-librespot[5646]: time="2024-08-15T17:18:27+10:00" level=info msg="generated new device id: 76ec8af9019d86abca8567f99bcf41aef3e5c702" Aug 15 17:18:27 minidsp-shd go-librespot[5646]: time="2024-08-15T17:18:27+10: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-08-15T17:18:27+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:27 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:27 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:28 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:28 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:29 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":62,"dbVolume":-49,"mute":false,"disableVolumeControl":false} Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 60 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 62 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:29 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 62 Aug 15 17:18:29 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":62,"dbVolume":-48.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Aug 15 17:18:29 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":62,"dbVolume":-48,"mute":false,"disableVolumeControl":false} Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Aug 15 17:18:29 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":63,"dbVolume":-47.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:29 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 62 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 63 Aug 15 17:18:29 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:29 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":63,"dbVolume":-47,"mute":false,"disableVolumeControl":false} Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:29 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 62 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 63 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":64,"dbVolume":-46.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 62 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:30 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 64 Aug 15 17:18:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 425. Aug 15 17:18:30 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:30 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:30 minidsp-shd go-librespot[5654]: Librespot-go daemon starting... Aug 15 17:18:30 minidsp-shd go-librespot[5654]: time="2024-08-15T17:18:30+10:00" level=info msg="generated new device id: 1d8a6c5b605256c9a9be630134e71d57cbd0c591" Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":64,"dbVolume":-46,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":64,"dbVolume":-45.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":65,"dbVolume":-45,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 65 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":65,"dbVolume":-44.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:30 minidsp-shd go-librespot[5654]: time="2024-08-15T17:18:30+10: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-08-15T17:18:30+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 64 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 65 Aug 15 17:18:30 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:30 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":65,"dbVolume":-44,"mute":false,"disableVolumeControl":false} Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:30 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:30 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 64 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 65 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":66,"dbVolume":-43.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 64 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 66 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:31 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 66 Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":69,"dbVolume":-39.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 66 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:31 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 69 Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":69,"dbVolume":-39,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 70 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 70 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:31 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:31 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:31 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":71,"dbVolume":-37.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:31 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:31 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 69 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 71 Aug 15 17:18:31 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:31 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 71 Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":71,"dbVolume":-37,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":71,"dbVolume":-36.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":72,"dbVolume":-36,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 71 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 72 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":72,"dbVolume":-35.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 71 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 72 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-35,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: SPOTIFY VOLUME 71 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: VOLUMIO VOLUME 73 Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 15 17:18:32 minidsp-shd volumio[662]: info: Setting Spotify Volume from Volumio: 73 Aug 15 17:18:32 minidsp-shd volumio[662]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34.5,"mute":false,"disableVolumeControl":false} Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreStateMachine::pushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioPushState Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output update for this device Aug 15 17:18:32 minidsp-shd volumio[662]: info: MRS: Pushing multiroomSync output Aug 15 17:18:32 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 15 17:18:32 minidsp-shd volumio[662]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Aug 15 17:18:33 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:33 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 426. Aug 15 17:18:33 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:33 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:33 minidsp-shd go-librespot[5662]: Librespot-go daemon starting... Aug 15 17:18:33 minidsp-shd go-librespot[5662]: time="2024-08-15T17:18:33+10:00" level=info msg="generated new device id: e4d2cf209de538a3fd9690341a74f083d12e048b" Aug 15 17:18:34 minidsp-shd go-librespot[5662]: time="2024-08-15T17:18:34+10: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-08-15T17:18:33+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:34 minidsp-shd volumio[662]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Aug 15 17:18:34 minidsp-shd volumio[662]: info: Sending Spotify command with payload to local API: /player/volume Aug 15 17:18:34 minidsp-shd volumio[662]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:34 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:34 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 427. Aug 15 17:18:37 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:37 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:37 minidsp-shd go-librespot[5670]: Librespot-go daemon starting... Aug 15 17:18:37 minidsp-shd go-librespot[5670]: time="2024-08-15T17:18:37+10:00" level=info msg="generated new device id: 52eb1ba3a9d585c3dc211c2ab273892b5dd26a16" Aug 15 17:18:37 minidsp-shd go-librespot[5670]: time="2024-08-15T17:18:37+10: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-08-15T17:18:37+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:37 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:37 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 428. Aug 15 17:18:40 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:40 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:40 minidsp-shd go-librespot[5679]: Librespot-go daemon starting... Aug 15 17:18:40 minidsp-shd go-librespot[5679]: time="2024-08-15T17:18:40+10:00" level=info msg="generated new device id: 0ba56a8f575ce69a2ed0dfe4467499e206a4862a" Aug 15 17:18:40 minidsp-shd go-librespot[5679]: time="2024-08-15T17:18:40+10: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-08-15T17:18:40+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:40 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:40 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:40 minidsp-shd volumio[662]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 15 17:18:43 minidsp-shd sudo[5688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 15 17:18:43 minidsp-shd sudo[5688]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 17:18:43 minidsp-shd sudo[5688]: pam_unix(sudo:session): session closed for user root Aug 15 17:18:43 minidsp-shd sudo[5691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 15 17:18:43 minidsp-shd sudo[5691]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 17:18:43 minidsp-shd sudo[5691]: pam_unix(sudo:session): session closed for user root Aug 15 17:18:43 minidsp-shd volumio[662]: verbose: New Socket.io Connection to 192.168.15.92 from 192.168.17.80 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Total Clients: 6 Aug 15 17:18:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 15 17:18:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 429. Aug 15 17:18:43 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 15 17:18:43 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 15 17:18:43 minidsp-shd go-librespot[5695]: Librespot-go daemon starting... Aug 15 17:18:43 minidsp-shd go-librespot[5695]: time="2024-08-15T17:18:43+10:00" level=info msg="generated new device id: 9f8e9342b9d4499a3d829b517ed07a7aae66efd5" Aug 15 17:18:43 minidsp-shd volumio[662]: info: Initializing connection to go-librespot Websocket Aug 15 17:18:43 minidsp-shd go-librespot[5695]: time="2024-08-15T17:18:43+10:00" level=debug msg="new websocket client" Aug 15 17:18:43 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket established Aug 15 17:18:43 minidsp-shd go-librespot[5695]: time="2024-08-15T17:18:43+10: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-08-15T17:18:43+10:00 is before 2025-01-06T00:00:00Z" Aug 15 17:18:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 15 17:18:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 15 17:18:43 minidsp-shd sudo[5704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 15 17:18:43 minidsp-shd sudo[5704]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 17:18:43 minidsp-shd sudo[5704]: pam_unix(sudo:session): session closed for user root Aug 15 17:18:43 minidsp-shd volumio[662]: info: Connection to go-librespot Websocket closed Aug 15 17:18:43 minidsp-shd sudo[5707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 15 17:18:43 minidsp-shd sudo[5707]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 15 17:18:43 minidsp-shd sudo[5707]: pam_unix(sudo:session): session closed for user root Aug 15 17:18:43 minidsp-shd volumio[662]: verbose: New Socket.io Connection to 192.168.15.92 from 192.168.17.80 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Total Clients: 7 Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetVisibleSources Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetState Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 15 17:18:44 minidsp-shd volumio[662]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 15 17:18:44 minidsp-shd volumio[662]: info: Received Get System Info Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 15 17:18:44 minidsp-shd volumio[662]: info: Discovery: Getting this device information Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetState Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::volumioGetState Aug 15 17:18:44 minidsp-shd volumio[662]: info: Listing playlists Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 15 17:18:44 minidsp-shd volumio[662]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 15 17:18:44 minidsp-shd volumio[662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 15 17:18:44 minidsp-shd volumio[662]: Error: certificate is not yet valid Aug 15 17:18:44 minidsp-shd volumio[662]: at TLSSocket.onConnectSecure (_tls_wrap.js:1515:34) Aug 15 17:18:44 minidsp-shd volumio[662]: at TLSSocket.emit (events.js:400:28) Aug 15 17:18:44 minidsp-shd volumio[662]: at TLSSocket._finishInit (_tls_wrap.js:937:8) Aug 15 17:18:44 minidsp-shd volumio[662]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:709:12) { Aug 15 17:18:44 minidsp-shd volumio[662]: code: 'CERT_NOT_YET_VALID' Aug 15 17:18:44 minidsp-shd volumio[662]: } Aug 15 17:18:44 minidsp-shd volumio[662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 15 17:18:45 minidsp-shd sudo[5720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-15 17:17 Aug 15 17:18:45 minidsp-shd sudo[5720]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"