-- Logs begin at Sat 2018-05-05 11:57:33 CEST, end at Sat 2018-05-05 14:25:55 CEST. -- May 05 14:24:00 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:00 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7479. May 05 14:24:01 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:01 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:01 volumio go-librespot[1084]: go-librespot daemon starting... May 05 14:24:01 volumio go-librespot[1084]: time="2018-05-05T14:24:01+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:01 volumio go-librespot[1084]: time="2018-05-05T14:24:01+02:00" level=debug msg="app state loaded" May 05 14:24:01 volumio go-librespot[1084]: time="2018-05-05T14:24:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:02 volumio go-librespot[1084]: time="2018-05-05T14:24:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:02+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:03 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:03 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7480. May 05 14:24:05 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:05 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:05 volumio go-librespot[1095]: go-librespot daemon starting... May 05 14:24:05 volumio go-librespot[1095]: time="2018-05-05T14:24:05+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:05 volumio go-librespot[1095]: time="2018-05-05T14:24:05+02:00" level=debug msg="app state loaded" May 05 14:24:05 volumio go-librespot[1095]: time="2018-05-05T14:24:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:05 volumio go-librespot[1095]: time="2018-05-05T14:24:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:05+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:06 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:06 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7481. May 05 14:24:08 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:08 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:08 volumio go-librespot[1106]: go-librespot daemon starting... May 05 14:24:08 volumio go-librespot[1106]: time="2018-05-05T14:24:08+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:08 volumio go-librespot[1106]: time="2018-05-05T14:24:08+02:00" level=debug msg="app state loaded" May 05 14:24:08 volumio go-librespot[1106]: time="2018-05-05T14:24:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:09 volumio go-librespot[1106]: time="2018-05-05T14:24:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:09+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:09 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:09 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:12 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:12 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7482. May 05 14:24:12 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:12 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:12 volumio go-librespot[1142]: go-librespot daemon starting... May 05 14:24:12 volumio go-librespot[1142]: time="2018-05-05T14:24:12+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:12 volumio go-librespot[1142]: time="2018-05-05T14:24:12+02:00" level=debug msg="app state loaded" May 05 14:24:12 volumio go-librespot[1142]: time="2018-05-05T14:24:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:12 volumio go-librespot[1142]: time="2018-05-05T14:24:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:12+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:15 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:15 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7483. May 05 14:24:15 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:15 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:15 volumio go-librespot[1159]: go-librespot daemon starting... May 05 14:24:15 volumio go-librespot[1159]: time="2018-05-05T14:24:15+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:15 volumio go-librespot[1159]: time="2018-05-05T14:24:15+02:00" level=debug msg="app state loaded" May 05 14:24:15 volumio go-librespot[1159]: time="2018-05-05T14:24:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:16 volumio go-librespot[1159]: time="2018-05-05T14:24:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:16+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:18 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:18 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7484. May 05 14:24:19 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:19 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:19 volumio go-librespot[1169]: go-librespot daemon starting... May 05 14:24:19 volumio go-librespot[1169]: time="2018-05-05T14:24:19+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:19 volumio go-librespot[1169]: time="2018-05-05T14:24:19+02:00" level=debug msg="app state loaded" May 05 14:24:19 volumio go-librespot[1169]: time="2018-05-05T14:24:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:19 volumio go-librespot[1169]: time="2018-05-05T14:24:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:19+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:21 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:21 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7485. May 05 14:24:22 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:22 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:22 volumio go-librespot[1200]: go-librespot daemon starting... May 05 14:24:22 volumio go-librespot[1200]: time="2018-05-05T14:24:22+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:22 volumio go-librespot[1200]: time="2018-05-05T14:24:22+02:00" level=debug msg="app state loaded" May 05 14:24:22 volumio go-librespot[1200]: time="2018-05-05T14:24:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:23 volumio go-librespot[1200]: time="2018-05-05T14:24:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:23+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:24 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:24 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7486. May 05 14:24:26 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:26 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:26 volumio go-librespot[1210]: go-librespot daemon starting... May 05 14:24:26 volumio go-librespot[1210]: time="2018-05-05T14:24:26+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:26 volumio go-librespot[1210]: time="2018-05-05T14:24:26+02:00" level=debug msg="app state loaded" May 05 14:24:26 volumio go-librespot[1210]: time="2018-05-05T14:24:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:26 volumio go-librespot[1210]: time="2018-05-05T14:24:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:26+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:27 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:27 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7487. May 05 14:24:29 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:29 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:29 volumio go-librespot[1221]: go-librespot daemon starting... May 05 14:24:29 volumio go-librespot[1221]: time="2018-05-05T14:24:29+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:29 volumio go-librespot[1221]: time="2018-05-05T14:24:29+02:00" level=debug msg="app state loaded" May 05 14:24:29 volumio go-librespot[1221]: time="2018-05-05T14:24:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:30 volumio go-librespot[1221]: time="2018-05-05T14:24:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:30+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:30 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:30 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:30 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:30 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:33 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:33 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7488. May 05 14:24:33 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:33 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:33 volumio go-librespot[1257]: go-librespot daemon starting... May 05 14:24:33 volumio go-librespot[1257]: time="2018-05-05T14:24:33+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:33 volumio go-librespot[1257]: time="2018-05-05T14:24:33+02:00" level=debug msg="app state loaded" May 05 14:24:33 volumio go-librespot[1257]: time="2018-05-05T14:24:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:33 volumio go-librespot[1257]: time="2018-05-05T14:24:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:33+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:36 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:36 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7489. May 05 14:24:36 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:36 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:36 volumio go-librespot[1267]: go-librespot daemon starting... May 05 14:24:36 volumio go-librespot[1267]: time="2018-05-05T14:24:36+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:36 volumio go-librespot[1267]: time="2018-05-05T14:24:36+02:00" level=debug msg="app state loaded" May 05 14:24:36 volumio go-librespot[1267]: time="2018-05-05T14:24:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:37 volumio go-librespot[1267]: time="2018-05-05T14:24:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:37+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:37 volumio volumio[954]: info: Updating volume from AirPlay: -15; 50% May 05 14:24:37 volumio volumio[954]: info: VolumeController::SetAlsaVolume50 May 05 14:24:37 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:37 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:37 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:38 volumio volumio[954]: info: Updating volume from AirPlay: -16.88; 44% May 05 14:24:38 volumio volumio[954]: info: VolumeController::SetAlsaVolume44 May 05 14:24:38 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:38 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:38 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:38 volumio volumio[954]: info: Updating volume from AirPlay: -20.62; 31% May 05 14:24:38 volumio volumio[954]: info: VolumeController::SetAlsaVolume31 May 05 14:24:38 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:38 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:38 volumio volumio[954]: info: Updating volume from AirPlay: -24.38; 19% May 05 14:24:38 volumio volumio[954]: info: VolumeController::SetAlsaVolume19 May 05 14:24:38 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:38 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:39 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:39 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:39 volumio volumio[954]: info: Updating volume from AirPlay: -22.5; 25% May 05 14:24:39 volumio volumio[954]: info: VolumeController::SetAlsaVolume25 May 05 14:24:39 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:39 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:39 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:39 volumio volumio[954]: info: Updating volume from AirPlay: -18.75; 38% May 05 14:24:39 volumio volumio[954]: info: VolumeController::SetAlsaVolume38 May 05 14:24:39 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:39 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7490. May 05 14:24:40 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:40 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:40 volumio go-librespot[1277]: go-librespot daemon starting... May 05 14:24:40 volumio go-librespot[1277]: time="2018-05-05T14:24:40+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:40 volumio go-librespot[1277]: time="2018-05-05T14:24:40+02:00" level=debug msg="app state loaded" May 05 14:24:40 volumio go-librespot[1277]: time="2018-05-05T14:24:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:40 volumio go-librespot[1277]: time="2018-05-05T14:24:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:40+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:42 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:42 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7491. May 05 14:24:43 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:43 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:43 volumio go-librespot[1301]: go-librespot daemon starting... May 05 14:24:43 volumio go-librespot[1301]: time="2018-05-05T14:24:43+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:43 volumio go-librespot[1301]: time="2018-05-05T14:24:43+02:00" level=debug msg="app state loaded" May 05 14:24:43 volumio go-librespot[1301]: time="2018-05-05T14:24:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:44 volumio go-librespot[1301]: time="2018-05-05T14:24:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:43+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:45 volumio volumio[954]: info: Updating volume from AirPlay: -16.88; 44% May 05 14:24:45 volumio volumio[954]: info: VolumeController::SetAlsaVolume44 May 05 14:24:45 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:45 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:45 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:45 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:45 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:45 volumio volumio[954]: info: Updating volume from AirPlay: -18.75; 38% May 05 14:24:45 volumio volumio[954]: info: VolumeController::SetAlsaVolume38 May 05 14:24:45 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:45 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:45 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7492. May 05 14:24:47 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:47 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:47 volumio go-librespot[1311]: go-librespot daemon starting... May 05 14:24:47 volumio go-librespot[1311]: time="2018-05-05T14:24:47+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:47 volumio go-librespot[1311]: time="2018-05-05T14:24:47+02:00" level=debug msg="app state loaded" May 05 14:24:47 volumio go-librespot[1311]: time="2018-05-05T14:24:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:47 volumio go-librespot[1311]: time="2018-05-05T14:24:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:47+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:48 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:48 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7493. May 05 14:24:50 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:50 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:50 volumio go-librespot[1362]: go-librespot daemon starting... May 05 14:24:50 volumio go-librespot[1362]: time="2018-05-05T14:24:50+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:50 volumio go-librespot[1362]: time="2018-05-05T14:24:50+02:00" level=debug msg="app state loaded" May 05 14:24:50 volumio go-librespot[1362]: time="2018-05-05T14:24:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:51 volumio go-librespot[1362]: time="2018-05-05T14:24:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:50+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:51 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:51 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:53 volumio shairport-sync[1190]: {"time":1525522031118,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 05 14:24:53 volumio shairport-sync[1190]: Dload Upload Total Spent Left Speed May 05 14:24:53 volumio volumio[954]: info: May 05 14:24:53 volumio volumio[954]: ---------------------------- Client requests Stop Airplay Playback May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 05 14:24:53 volumio volumio[954]: info: Airplay playback stop May 05 14:24:53 volumio volumio[954]: info: Airplay Playback Stopped, clearing state May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::resetVolumioState May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::getcurrentVolume May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::volumioRetrievevolume May 05 14:24:53 volumio shairport-sync[1190]: [158B blob data] May 05 14:24:53 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:53 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:54 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:54 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7494. May 05 14:24:54 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:54 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:54 volumio go-librespot[1388]: go-librespot daemon starting... May 05 14:24:54 volumio go-librespot[1388]: time="2018-05-05T14:24:54+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:54 volumio go-librespot[1388]: time="2018-05-05T14:24:54+02:00" level=debug msg="app state loaded" May 05 14:24:54 volumio go-librespot[1388]: time="2018-05-05T14:24:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:54 volumio go-librespot[1388]: time="2018-05-05T14:24:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:54+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:57 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:24:57 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:24:57 volumio volumio[954]: info: Airplay started streaming, receiving metadatas May 05 14:24:57 volumio shairport-sync[1190]: {"time":1525523093597,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 05 14:24:57 volumio shairport-sync[1190]: Dload Upload Total Spent Left Speed May 05 14:24:57 volumio volumio[954]: info: May 05 14:24:57 volumio volumio[954]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 05 14:24:57 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 05 14:24:57 volumio volumio[954]: info: Airplay playback start May 05 14:24:57 volumio shairport-sync[1190]: [158B blob data] May 05 14:24:57 volumio volumio[954]: info: Updating volume from AirPlay: -20; 33% May 05 14:24:57 volumio volumio[954]: info: VolumeController::SetAlsaVolume33 May 05 14:24:57 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:57 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:24:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7495. May 05 14:24:57 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:24:57 volumio systemd[1]: Started go-librespot Daemon. May 05 14:24:57 volumio go-librespot[1407]: go-librespot daemon starting... May 05 14:24:57 volumio go-librespot[1407]: time="2018-05-05T14:24:57+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:24:57 volumio go-librespot[1407]: time="2018-05-05T14:24:57+02:00" level=debug msg="app state loaded" May 05 14:24:57 volumio go-librespot[1407]: time="2018-05-05T14:24:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:24:58 volumio go-librespot[1407]: time="2018-05-05T14:24:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:24:58+02:00 is before 2026-07-09T00:00:00Z" May 05 14:24:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:24:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:58 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:58 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:58 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:58 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:58 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:24:59 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:24:59 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:00 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:00 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7496. May 05 14:25:01 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:01 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:01 volumio go-librespot[1432]: go-librespot daemon starting... May 05 14:25:01 volumio go-librespot[1432]: time="2018-05-05T14:25:01+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:01 volumio go-librespot[1432]: time="2018-05-05T14:25:01+02:00" level=debug msg="app state loaded" May 05 14:25:01 volumio go-librespot[1432]: time="2018-05-05T14:25:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:01 volumio go-librespot[1432]: time="2018-05-05T14:25:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:01+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:02 volumio volumio[954]: info: Updating volume from AirPlay: -18.12; 40% May 05 14:25:02 volumio volumio[954]: info: VolumeController::SetAlsaVolume40 May 05 14:25:02 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:02 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:02 volumio volumio[954]: info: Updating volume from AirPlay: -16.25; 46% May 05 14:25:02 volumio volumio[954]: info: VolumeController::SetAlsaVolume46 May 05 14:25:02 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:02 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -14.37; 52% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume52 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -12.5; 58% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume58 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:03 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -10.62; 65% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume65 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -8.75; 71% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume71 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -6.87; 77% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume77 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:03 volumio volumio[954]: info: Updating volume from AirPlay: -5; 83% May 05 14:25:03 volumio volumio[954]: info: VolumeController::SetAlsaVolume83 May 05 14:25:03 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:03 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:04 volumio volumio[954]: info: Updating volume from AirPlay: -6.87; 77% May 05 14:25:04 volumio volumio[954]: info: VolumeController::SetAlsaVolume77 May 05 14:25:04 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:04 volumio volumio[954]: info: Updating volume from AirPlay: -8.75; 71% May 05 14:25:04 volumio volumio[954]: info: VolumeController::SetAlsaVolume71 May 05 14:25:04 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:04 volumio volumio[954]: info: Updating volume from AirPlay: -10.62; 65% May 05 14:25:04 volumio volumio[954]: info: VolumeController::SetAlsaVolume65 May 05 14:25:04 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7497. May 05 14:25:04 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:04 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:04 volumio go-librespot[1442]: go-librespot daemon starting... May 05 14:25:04 volumio go-librespot[1442]: time="2018-05-05T14:25:04+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:04 volumio go-librespot[1442]: time="2018-05-05T14:25:04+02:00" level=debug msg="app state loaded" May 05 14:25:04 volumio volumio[954]: info: Updating volume from AirPlay: -12.5; 58% May 05 14:25:04 volumio volumio[954]: info: VolumeController::SetAlsaVolume58 May 05 14:25:04 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:04 volumio go-librespot[1442]: time="2018-05-05T14:25:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:05 volumio go-librespot[1442]: time="2018-05-05T14:25:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:05+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:05 volumio volumio[954]: info: Updating volume from AirPlay: -14.37; 52% May 05 14:25:05 volumio volumio[954]: info: VolumeController::SetAlsaVolume52 May 05 14:25:05 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:05 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:05 volumio volumio[954]: info: Updating volume from AirPlay: -16.25; 46% May 05 14:25:05 volumio volumio[954]: info: VolumeController::SetAlsaVolume46 May 05 14:25:05 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:05 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:05 volumio volumio[954]: info: Updating volume from AirPlay: -18.12; 40% May 05 14:25:05 volumio volumio[954]: info: VolumeController::SetAlsaVolume40 May 05 14:25:05 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:05 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:05 volumio volumio[954]: info: Updating volume from AirPlay: -20; 33% May 05 14:25:05 volumio volumio[954]: info: VolumeController::SetAlsaVolume33 May 05 14:25:05 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:05 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:06 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:06 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:06 volumio volumio[954]: info: Updating volume from AirPlay: -18.12; 40% May 05 14:25:06 volumio volumio[954]: info: VolumeController::SetAlsaVolume40 May 05 14:25:06 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:06 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7498. May 05 14:25:08 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:08 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:08 volumio go-librespot[1452]: go-librespot daemon starting... May 05 14:25:08 volumio go-librespot[1452]: time="2018-05-05T14:25:08+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:08 volumio go-librespot[1452]: time="2018-05-05T14:25:08+02:00" level=debug msg="app state loaded" May 05 14:25:08 volumio go-librespot[1452]: time="2018-05-05T14:25:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:08 volumio go-librespot[1452]: time="2018-05-05T14:25:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:08+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:08 volumio volumio[954]: info: Updating volume from AirPlay: -0.64; 98% May 05 14:25:08 volumio volumio[954]: info: VolumeController::SetAlsaVolume98 May 05 14:25:08 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:08 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:08 volumio volumio[954]: info: Updating volume from AirPlay: 0; 100% May 05 14:25:08 volumio volumio[954]: info: VolumeController::SetAlsaVolume100 May 05 14:25:08 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:08 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:09 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:09 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:09 volumio volumio[954]: info: Updating volume from AirPlay: -19.47; 35% May 05 14:25:09 volumio volumio[954]: info: VolumeController::SetAlsaVolume35 May 05 14:25:09 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:09 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:09 volumio volumio[954]: info: Updating volume from AirPlay: -14.11; 53% May 05 14:25:09 volumio volumio[954]: info: VolumeController::SetAlsaVolume53 May 05 14:25:09 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:09 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:10 volumio volumio[954]: info: Updating volume from AirPlay: -18.54; 38% May 05 14:25:10 volumio volumio[954]: info: VolumeController::SetAlsaVolume38 May 05 14:25:10 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:10 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:10 volumio volumio[954]: info: Updating volume from AirPlay: -18.99; 37% May 05 14:25:10 volumio volumio[954]: info: VolumeController::SetAlsaVolume37 May 05 14:25:10 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:10 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7499. May 05 14:25:11 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:11 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:11 volumio go-librespot[1477]: go-librespot daemon starting... May 05 14:25:11 volumio go-librespot[1477]: time="2018-05-05T14:25:11+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:11 volumio go-librespot[1477]: time="2018-05-05T14:25:11+02:00" level=debug msg="app state loaded" May 05 14:25:11 volumio go-librespot[1477]: time="2018-05-05T14:25:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:12 volumio go-librespot[1477]: time="2018-05-05T14:25:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:12+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:12 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:12 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::servicePushState May 05 14:25:14 volumio volumio[954]: info: CoreStateMachine::pushState May 05 14:25:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState May 05 14:25:15 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:15 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7500. May 05 14:25:15 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:15 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:15 volumio go-librespot[1487]: go-librespot daemon starting... May 05 14:25:15 volumio go-librespot[1487]: time="2018-05-05T14:25:15+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:15 volumio go-librespot[1487]: time="2018-05-05T14:25:15+02:00" level=debug msg="app state loaded" May 05 14:25:15 volumio go-librespot[1487]: time="2018-05-05T14:25:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:15 volumio go-librespot[1487]: time="2018-05-05T14:25:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:15+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:18 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:18 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7501. May 05 14:25:18 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:18 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:18 volumio go-librespot[1497]: go-librespot daemon starting... May 05 14:25:18 volumio go-librespot[1497]: time="2018-05-05T14:25:18+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:18 volumio go-librespot[1497]: time="2018-05-05T14:25:18+02:00" level=debug msg="app state loaded" May 05 14:25:18 volumio go-librespot[1497]: time="2018-05-05T14:25:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:19 volumio go-librespot[1497]: time="2018-05-05T14:25:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:19+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:21 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:21 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7502. May 05 14:25:22 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:22 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:22 volumio go-librespot[1521]: go-librespot daemon starting... May 05 14:25:22 volumio go-librespot[1521]: time="2018-05-05T14:25:22+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:22 volumio go-librespot[1521]: time="2018-05-05T14:25:22+02:00" level=debug msg="app state loaded" May 05 14:25:22 volumio go-librespot[1521]: time="2018-05-05T14:25:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:22 volumio go-librespot[1521]: time="2018-05-05T14:25:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:22+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:22 volumio nmbd[689]: [2018/05/05 14:25:22.977871, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 14:25:22 volumio nmbd[689]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 05 14:25:24 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:24 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7503. May 05 14:25:25 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:25 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:25 volumio go-librespot[1531]: go-librespot daemon starting... May 05 14:25:25 volumio go-librespot[1531]: time="2018-05-05T14:25:25+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:25 volumio go-librespot[1531]: time="2018-05-05T14:25:25+02:00" level=debug msg="app state loaded" May 05 14:25:25 volumio go-librespot[1531]: time="2018-05-05T14:25:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:26 volumio go-librespot[1531]: time="2018-05-05T14:25:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:25+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:27 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:27 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7504. May 05 14:25:29 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:29 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:29 volumio go-librespot[1541]: go-librespot daemon starting... May 05 14:25:29 volumio go-librespot[1541]: time="2018-05-05T14:25:29+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:29 volumio go-librespot[1541]: time="2018-05-05T14:25:29+02:00" level=debug msg="app state loaded" May 05 14:25:29 volumio go-librespot[1541]: time="2018-05-05T14:25:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:29 volumio go-librespot[1541]: time="2018-05-05T14:25:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:29+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:30 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:30 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7505. May 05 14:25:32 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:32 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:32 volumio go-librespot[1565]: go-librespot daemon starting... May 05 14:25:32 volumio go-librespot[1565]: time="2018-05-05T14:25:32+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:32 volumio go-librespot[1565]: time="2018-05-05T14:25:32+02:00" level=debug msg="app state loaded" May 05 14:25:32 volumio go-librespot[1565]: time="2018-05-05T14:25:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:33 volumio go-librespot[1565]: time="2018-05-05T14:25:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:32+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:33 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:33 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:36 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:36 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7506. May 05 14:25:36 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:36 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:36 volumio go-librespot[1575]: go-librespot daemon starting... May 05 14:25:36 volumio go-librespot[1575]: time="2018-05-05T14:25:36+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:36 volumio go-librespot[1575]: time="2018-05-05T14:25:36+02:00" level=debug msg="app state loaded" May 05 14:25:36 volumio go-librespot[1575]: time="2018-05-05T14:25:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:36 volumio go-librespot[1575]: time="2018-05-05T14:25:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:36+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:39 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:39 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7507. May 05 14:25:39 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:39 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:39 volumio go-librespot[1585]: go-librespot daemon starting... May 05 14:25:39 volumio go-librespot[1585]: time="2018-05-05T14:25:39+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:39 volumio go-librespot[1585]: time="2018-05-05T14:25:39+02:00" level=debug msg="app state loaded" May 05 14:25:39 volumio go-librespot[1585]: time="2018-05-05T14:25:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:40 volumio go-librespot[1585]: time="2018-05-05T14:25:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:40+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:42 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:42 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7508. May 05 14:25:43 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:43 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:43 volumio go-librespot[1609]: go-librespot daemon starting... May 05 14:25:43 volumio go-librespot[1609]: time="2018-05-05T14:25:43+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:43 volumio go-librespot[1609]: time="2018-05-05T14:25:43+02:00" level=debug msg="app state loaded" May 05 14:25:43 volumio go-librespot[1609]: time="2018-05-05T14:25:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:43 volumio go-librespot[1609]: time="2018-05-05T14:25:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:43+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:45 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:45 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7509. May 05 14:25:46 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:46 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:46 volumio go-librespot[1619]: go-librespot daemon starting... May 05 14:25:46 volumio go-librespot[1619]: time="2018-05-05T14:25:46+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:46 volumio go-librespot[1619]: time="2018-05-05T14:25:46+02:00" level=debug msg="app state loaded" May 05 14:25:46 volumio go-librespot[1619]: time="2018-05-05T14:25:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:47 volumio go-librespot[1619]: time="2018-05-05T14:25:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:47+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:48 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:48 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7510. May 05 14:25:50 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:50 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:50 volumio go-librespot[1629]: go-librespot daemon starting... May 05 14:25:50 volumio go-librespot[1629]: time="2018-05-05T14:25:50+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:50 volumio go-librespot[1629]: time="2018-05-05T14:25:50+02:00" level=debug msg="app state loaded" May 05 14:25:50 volumio go-librespot[1629]: time="2018-05-05T14:25:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:50 volumio go-librespot[1629]: time="2018-05-05T14:25:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:50+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:51 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:51 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 14:25:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7511. May 05 14:25:53 volumio systemd[1]: Stopped go-librespot Daemon. May 05 14:25:53 volumio systemd[1]: Started go-librespot Daemon. May 05 14:25:53 volumio go-librespot[1691]: go-librespot daemon starting... May 05 14:25:53 volumio sudo[1694]: pam_unix(sudo:account): account volumio has password changed in future May 05 14:25:53 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:25:53 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:25:53 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root May 05 14:25:53 volumio go-librespot[1691]: time="2018-05-05T14:25:53+02:00" level=info msg="running go-librespot 0.7.1" May 05 14:25:53 volumio go-librespot[1691]: time="2018-05-05T14:25:53+02:00" level=debug msg="app state loaded" May 05 14:25:53 volumio sudo[1701]: pam_unix(sudo:account): account volumio has password changed in future May 05 14:25:53 volumio sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:25:53 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:25:53 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root May 05 14:25:54 volumio go-librespot[1691]: time="2018-05-05T14:25:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 05 14:25:54 volumio volumio[954]: verbose: New Socket.io Connection to 192.168.50.200 from 192.168.50.49 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetVisibleSources May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 14:25:54 volumio volumio[954]: info: Received Get System Info May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 14:25:54 volumio volumio[954]: info: Discovery: Getting this device information May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: Listing playlists May 05 14:25:54 volumio go-librespot[1691]: time="2018-05-05T14:25:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T14:25:54+02:00 is before 2026-07-09T00:00:00Z" May 05 14:25:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 14:25:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 14:25:54 volumio volumio[954]: info: Initializing connection to go-librespot Websocket May 05 14:25:54 volumio volumio[954]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 14:25:54 volumio sudo[1708]: pam_unix(sudo:account): account volumio has password changed in future May 05 14:25:54 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:25:54 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:25:54 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root May 05 14:25:54 volumio sudo[1711]: pam_unix(sudo:account): account volumio has password changed in future May 05 14:25:54 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:25:54 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:25:54 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root May 05 14:25:54 volumio volumio[954]: verbose: New Socket.io Connection to 192.168.50.200 from 192.168.50.49 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetVisibleSources May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 14:25:54 volumio volumio[954]: info: Received Get System Info May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 14:25:54 volumio volumio[954]: info: Discovery: Getting this device information May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::volumioGetState May 05 14:25:54 volumio volumio[954]: info: Listing playlists May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 14:25:54 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 14:25:55 volumio volumio[954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:25:55 volumio volumio[954]: Error: certificate is not yet valid May 05 14:25:55 volumio volumio[954]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 05 14:25:55 volumio volumio[954]: at TLSSocket.emit (events.js:315:20) May 05 14:25:55 volumio volumio[954]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 05 14:25:55 volumio volumio[954]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 05 14:25:55 volumio volumio[954]: code: 'CERT_NOT_YET_VALID' May 05 14:25:55 volumio volumio[954]: } May 05 14:25:55 volumio volumio[954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:25:55 volumio sudo[1722]: pam_unix(sudo:account): account volumio has password changed in future May 05 14:25:55 volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2018-05-05 14:24 May 05 14:25:55 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"