-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Fri 2024-07-12 15:10:42 KST. -- Jul 12 15:09:00 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:00 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 777. Jul 12 15:09:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:01 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:01 volumio go-librespot[14711]: Librespot-go daemon starting... Jul 12 15:09:01 volumio go-librespot[14711]: time="2024-07-12T15:09:01+09:00" level=info msg="generated new device id: b396acb5f76340741453b9ebed64c210ef81b4e1" Jul 12 15:09:01 volumio go-librespot[14711]: time="2024-07-12T15:09:01+09: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 signed by unknown authority" Jul 12 15:09:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:03 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:03 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 778. Jul 12 15:09:04 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:04 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:04 volumio go-librespot[14727]: Librespot-go daemon starting... Jul 12 15:09:04 volumio go-librespot[14727]: time="2024-07-12T15:09:04+09:00" level=info msg="generated new device id: 268621bd40bda8bf13502118c84a2895cd9c91d6" Jul 12 15:09:04 volumio go-librespot[14727]: time="2024-07-12T15:09:04+09: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 signed by unknown authority" Jul 12 15:09:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:06 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:06 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 779. Jul 12 15:09:07 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:07 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:07 volumio go-librespot[14742]: Librespot-go daemon starting... Jul 12 15:09:07 volumio go-librespot[14742]: time="2024-07-12T15:09:07+09:00" level=info msg="generated new device id: 4c36eb76fd6677da05d8507f673e632731de0e54" Jul 12 15:09:07 volumio go-librespot[14742]: time="2024-07-12T15:09:07+09: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 signed by unknown authority" Jul 12 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:09 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:09 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 780. Jul 12 15:09:11 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:11 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:11 volumio go-librespot[14757]: Librespot-go daemon starting... Jul 12 15:09:11 volumio go-librespot[14757]: time="2024-07-12T15:09:11+09:00" level=info msg="generated new device id: e74d3c6ba61f292de1e15171b652c4e6a5d5fade" Jul 12 15:09:11 volumio go-librespot[14757]: time="2024-07-12T15:09:11+09: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 signed by unknown authority" Jul 12 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:12 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:12 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 781. Jul 12 15:09:14 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:14 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:14 volumio go-librespot[14773]: Librespot-go daemon starting... Jul 12 15:09:14 volumio go-librespot[14773]: time="2024-07-12T15:09:14+09:00" level=info msg="generated new device id: 34f410ed618f8337827ccb8ce1747c7977013e94" Jul 12 15:09:14 volumio go-librespot[14773]: time="2024-07-12T15:09:14+09: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 signed by unknown authority" Jul 12 15:09:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:15 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:15 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 782. Jul 12 15:09:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:17 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:17 volumio go-librespot[14787]: Librespot-go daemon starting... Jul 12 15:09:17 volumio go-librespot[14787]: time="2024-07-12T15:09:17+09:00" level=info msg="generated new device id: c8409047922a1d53306c86204ec91b02f4151ce9" Jul 12 15:09:17 volumio go-librespot[14787]: time="2024-07-12T15:09:17+09: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 signed by unknown authority" Jul 12 15:09:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:18 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:18 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 783. Jul 12 15:09:20 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:20 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:20 volumio go-librespot[14801]: Librespot-go daemon starting... Jul 12 15:09:20 volumio go-librespot[14801]: time="2024-07-12T15:09:20+09:00" level=info msg="generated new device id: 08fd2239722a14bea4ae3244754cff632e67d32c" Jul 12 15:09:20 volumio go-librespot[14801]: time="2024-07-12T15:09:20+09: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 signed by unknown authority" Jul 12 15:09:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:21 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:21 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 784. Jul 12 15:09:24 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:24 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:24 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:24 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:24 volumio go-librespot[14815]: Librespot-go daemon starting... Jul 12 15:09:24 volumio go-librespot[14815]: time="2024-07-12T15:09:24+09:00" level=info msg="generated new device id: db976e087de5f752fedddab2dfa35fd7e71f7e07" Jul 12 15:09:24 volumio go-librespot[14815]: time="2024-07-12T15:09:24+09: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 signed by unknown authority" Jul 12 15:09:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:27 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:27 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 785. Jul 12 15:09:27 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:27 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:27 volumio go-librespot[14829]: Librespot-go daemon starting... Jul 12 15:09:27 volumio go-librespot[14829]: time="2024-07-12T15:09:27+09:00" level=info msg="generated new device id: ca3db6a164aae1e3740c636d8b73d67eaeec6038" Jul 12 15:09:27 volumio go-librespot[14829]: time="2024-07-12T15:09:27+09: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 signed by unknown authority" Jul 12 15:09:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:30 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:30 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 786. Jul 12 15:09:30 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:30 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:30 volumio go-librespot[14846]: Librespot-go daemon starting... Jul 12 15:09:30 volumio go-librespot[14846]: time="2024-07-12T15:09:30+09:00" level=info msg="generated new device id: dd13457abd55df6425accda3352d60246286f3f9" Jul 12 15:09:30 volumio go-librespot[14846]: time="2024-07-12T15:09:30+09: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 signed by unknown authority" Jul 12 15:09:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:33 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:33 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 787. Jul 12 15:09:34 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:34 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:34 volumio go-librespot[14860]: Librespot-go daemon starting... Jul 12 15:09:34 volumio go-librespot[14860]: time="2024-07-12T15:09:34+09:00" level=info msg="generated new device id: b8622dc9229f1ced19c8ca2a3ae7e6b5cc5506bf" Jul 12 15:09:34 volumio go-librespot[14860]: time="2024-07-12T15:09:34+09: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 signed by unknown authority" Jul 12 15:09:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:36 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:36 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 788. Jul 12 15:09:37 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:37 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:37 volumio go-librespot[14874]: Librespot-go daemon starting... Jul 12 15:09:37 volumio go-librespot[14874]: time="2024-07-12T15:09:37+09:00" level=info msg="generated new device id: 6862b526a8812363fc237a17712bce3eeeefec8d" Jul 12 15:09:37 volumio go-librespot[14874]: time="2024-07-12T15:09:37+09: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 signed by unknown authority" Jul 12 15:09:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:39 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:39 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 789. Jul 12 15:09:40 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:40 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:40 volumio go-librespot[14888]: Librespot-go daemon starting... Jul 12 15:09:40 volumio go-librespot[14888]: time="2024-07-12T15:09:40+09:00" level=info msg="generated new device id: f941ac3b951e6377348e4a8a6b11fcb438d4c229" Jul 12 15:09:40 volumio go-librespot[14888]: time="2024-07-12T15:09:40+09: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 signed by unknown authority" Jul 12 15:09:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:42 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:42 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 790. Jul 12 15:09:43 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:43 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:43 volumio go-librespot[14904]: Librespot-go daemon starting... Jul 12 15:09:43 volumio go-librespot[14904]: time="2024-07-12T15:09:43+09:00" level=info msg="generated new device id: 37f1c43c9d47c014ee1c228dc9304b63186e811a" Jul 12 15:09:43 volumio go-librespot[14904]: time="2024-07-12T15:09:43+09: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 signed by unknown authority" Jul 12 15:09:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:45 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:45 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 791. Jul 12 15:09:47 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:47 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:47 volumio go-librespot[14918]: Librespot-go daemon starting... Jul 12 15:09:47 volumio go-librespot[14918]: time="2024-07-12T15:09:47+09:00" level=info msg="generated new device id: f7e666e60dc1bacd4d57635a50fb5e490046c35c" Jul 12 15:09:47 volumio go-librespot[14918]: time="2024-07-12T15:09:47+09: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 signed by unknown authority" Jul 12 15:09:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:48 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:48 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 792. Jul 12 15:09:50 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:50 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:50 volumio go-librespot[14932]: Librespot-go daemon starting... Jul 12 15:09:50 volumio go-librespot[14932]: time="2024-07-12T15:09:50+09:00" level=info msg="generated new device id: 32a383c92850d8ada33b17e6ab12d78d4ea27423" Jul 12 15:09:50 volumio go-librespot[14932]: time="2024-07-12T15:09:50+09: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 signed by unknown authority" Jul 12 15:09:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:51 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:51 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 793. Jul 12 15:09:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:53 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:53 volumio go-librespot[14986]: Librespot-go daemon starting... Jul 12 15:09:53 volumio go-librespot[14986]: time="2024-07-12T15:09:53+09:00" level=info msg="generated new device id: 191308d16165fa4ce9428671bd2090567d85cd84" Jul 12 15:09:53 volumio go-librespot[14986]: time="2024-07-12T15:09:53+09: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 signed by unknown authority" Jul 12 15:09:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:54 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:54 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:09:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:09:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 794. Jul 12 15:09:56 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:09:56 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:09:56 volumio go-librespot[15002]: Librespot-go daemon starting... Jul 12 15:09:56 volumio go-librespot[15002]: time="2024-07-12T15:09:56+09:00" level=info msg="generated new device id: 2c9cb61df533054b58e92dbf7166870f6760b29d" Jul 12 15:09:57 volumio go-librespot[15002]: time="2024-07-12T15:09:57+09: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 signed by unknown authority" Jul 12 15:09:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:09:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:09:57 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:09:57 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 795. Jul 12 15:10:00 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:00 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:00 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:00 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:00 volumio go-librespot[15016]: Librespot-go daemon starting... Jul 12 15:10:00 volumio go-librespot[15016]: time="2024-07-12T15:10:00+09:00" level=info msg="generated new device id: c628387973ca42888fd9723cebb8894cb6c5a1f8" Jul 12 15:10:00 volumio go-librespot[15016]: time="2024-07-12T15:10:00+09: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 signed by unknown authority" Jul 12 15:10:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:03 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:03 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 796. Jul 12 15:10:03 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:03 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:03 volumio go-librespot[15030]: Librespot-go daemon starting... Jul 12 15:10:03 volumio go-librespot[15030]: time="2024-07-12T15:10:03+09:00" level=info msg="generated new device id: e3ec3d444dad75f01bb5bbe30c29556006fa53be" Jul 12 15:10:03 volumio go-librespot[15030]: time="2024-07-12T15:10:03+09: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 signed by unknown authority" Jul 12 15:10:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:06 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:06 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 797. Jul 12 15:10:06 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:06 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:06 volumio go-librespot[15044]: Librespot-go daemon starting... Jul 12 15:10:06 volumio go-librespot[15044]: time="2024-07-12T15:10:06+09:00" level=info msg="generated new device id: f914d6a4177eef124a5c9378e1404bd93c46e976" Jul 12 15:10:06 volumio go-librespot[15044]: time="2024-07-12T15:10:06+09: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 signed by unknown authority" Jul 12 15:10:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:09 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:09 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 798. Jul 12 15:10:09 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:09 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:09 volumio go-librespot[15058]: Librespot-go daemon starting... Jul 12 15:10:09 volumio go-librespot[15058]: time="2024-07-12T15:10:09+09:00" level=info msg="generated new device id: ce6e11751877c4d46f6f39b4b9600cb293a9af58" Jul 12 15:10:09 volumio go-librespot[15058]: time="2024-07-12T15:10:09+09: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 signed by unknown authority" Jul 12 15:10:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:12 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:12 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 799. Jul 12 15:10:13 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:13 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:13 volumio go-librespot[15074]: Librespot-go daemon starting... Jul 12 15:10:13 volumio go-librespot[15074]: time="2024-07-12T15:10:13+09:00" level=info msg="generated new device id: 33af083f6692599f070044b6a6dd00d75f0e66c1" Jul 12 15:10:13 volumio go-librespot[15074]: time="2024-07-12T15:10:13+09: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 signed by unknown authority" Jul 12 15:10:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:15 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:15 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 800. Jul 12 15:10:16 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:16 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:16 volumio go-librespot[15089]: Librespot-go daemon starting... Jul 12 15:10:16 volumio go-librespot[15089]: time="2024-07-12T15:10:16+09:00" level=info msg="generated new device id: ae743f494d92ef7f1e6e709d4a246921739f61da" Jul 12 15:10:16 volumio go-librespot[15089]: time="2024-07-12T15:10:16+09: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 signed by unknown authority" Jul 12 15:10:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:18 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:18 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 801. Jul 12 15:10:19 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:19 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:19 volumio go-librespot[15103]: Librespot-go daemon starting... Jul 12 15:10:19 volumio go-librespot[15103]: time="2024-07-12T15:10:19+09:00" level=info msg="generated new device id: f314ccbe786888bad5ccd3a765e86da9df43dc34" Jul 12 15:10:19 volumio go-librespot[15103]: time="2024-07-12T15:10:19+09: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 signed by unknown authority" Jul 12 15:10:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:21 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:21 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 802. Jul 12 15:10:22 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:22 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:22 volumio go-librespot[15117]: Librespot-go daemon starting... Jul 12 15:10:22 volumio go-librespot[15117]: time="2024-07-12T15:10:22+09:00" level=info msg="generated new device id: f714a0ead7241b3fb5afa01be47e492c3e0a7844" Jul 12 15:10:22 volumio go-librespot[15117]: time="2024-07-12T15:10:22+09: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 signed by unknown authority" Jul 12 15:10:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:24 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:24 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 803. Jul 12 15:10:26 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:26 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:26 volumio go-librespot[15131]: Librespot-go daemon starting... Jul 12 15:10:26 volumio go-librespot[15131]: time="2024-07-12T15:10:26+09:00" level=info msg="generated new device id: 7bd516926656feb86dd41cb2fd4ed12f4f03040d" Jul 12 15:10:26 volumio go-librespot[15131]: time="2024-07-12T15:10:26+09: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 signed by unknown authority" Jul 12 15:10:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:27 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:27 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 804. Jul 12 15:10:29 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:29 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:29 volumio go-librespot[15147]: Librespot-go daemon starting... Jul 12 15:10:29 volumio go-librespot[15147]: time="2024-07-12T15:10:29+09:00" level=info msg="generated new device id: ab0bde0eda2347c516407de5fb8d7ccf817677a3" Jul 12 15:10:29 volumio go-librespot[15147]: time="2024-07-12T15:10:29+09: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 signed by unknown authority" Jul 12 15:10:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:30 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:30 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 805. Jul 12 15:10:32 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:32 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:32 volumio go-librespot[15161]: Librespot-go daemon starting... Jul 12 15:10:32 volumio go-librespot[15161]: time="2024-07-12T15:10:32+09:00" level=info msg="generated new device id: c58f6d90842746c6ff4891d5aa5121ef7b2f824b" Jul 12 15:10:32 volumio go-librespot[15161]: time="2024-07-12T15:10:32+09: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 signed by unknown authority" Jul 12 15:10:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:33 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:33 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 806. Jul 12 15:10:35 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:35 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:35 volumio go-librespot[15175]: Librespot-go daemon starting... Jul 12 15:10:35 volumio go-librespot[15175]: time="2024-07-12T15:10:35+09:00" level=info msg="generated new device id: a6192215752d0602052489199fa0666e6c261aaa" Jul 12 15:10:35 volumio go-librespot[15175]: time="2024-07-12T15:10:35+09: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 signed by unknown authority" Jul 12 15:10:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:36 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:36 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 807. Jul 12 15:10:39 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:39 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:39 volumio go-librespot[15189]: Librespot-go daemon starting... Jul 12 15:10:39 volumio go-librespot[15189]: time="2024-07-12T15:10:39+09:00" level=info msg="generated new device id: 967b90550df4e0acaeff5bc1030903fee59f2acb" Jul 12 15:10:39 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:39 volumio go-librespot[15189]: time="2024-07-12T15:10:39+09:00" level=debug msg="new websocket client" Jul 12 15:10:39 volumio volumio[1111]: info: Connection to go-librespot Websocket established Jul 12 15:10:39 volumio go-librespot[15189]: time="2024-07-12T15:10:39+09: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 signed by unknown authority" Jul 12 15:10:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:39 volumio volumio[1111]: info: Connection to go-librespot Websocket closed Jul 12 15:10:41 volumio volumio[1111]: info: Preload queue cleared Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::ClearQueue Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::stop Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::stPlaybackTimer Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::updateTrackBlock Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrackBlock Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 12 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::serviceStop Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 12 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::serviceStop Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::stop Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::clearPlayQueue Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::saveQueue Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushQueue Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::addQueueItems Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::addQueueItems Jul 12 15:10:41 volumio volumio[1111]: info: Preload queue cleared Jul 12 15:10:41 volumio volumio[1111]: info: Adding Item to queue: music-library/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence Jul 12 15:10:41 volumio volumio[1111]: info: Exploding uri music-library/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence in service mpd Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20A%20Mirage%20On%20The%20Water.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Bell%20Song.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Bell Song.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Green%20Hills.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Green Hills.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Lake%20Louise.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Lake Louise.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Last%20Summer.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Last Summer.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20On%20the%20Shore.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - On the Shore.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Paris%20In%20Winter.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Paris In Winter.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Prologue%20II.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Prologue II.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Ripples.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Ripples.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Sighing%20Wind.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Sighing Wind.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: ALBUMART /albumart?cacheid=216&web=Yuhki%20Kuramoto/Reminiscence/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FnewAge%2FYuhki%20Kuramoto%20%EC%9C%A0%ED%82%A4%EA%B5%AC%EB%9D%BC%EB%AA%A8%ED%86%A0%20(1998%20~%202010%EC%95%A8%EB%B2%9416%EC%9E%A5)%2F1998-03%20Yuhki%20Kuramoto-Reminiscence%2FYuhki%20Kuramoto%20-%20Sonnet%20Of%20The%20Woods.mp3&metadata=false Jul 12 15:10:41 volumio volumio[1111]: info: URI /mnt/USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - Sonnet Of The Woods.mp3 Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand stop took 24 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushQueue Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::saveQueue Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::updateTrackBlock Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrackBlock Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPlay Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::play index 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::stop Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::play index undefined Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::startPlaybackTimer Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::clearAddPlayTracks USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3 Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 16 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand stop took 7 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 6 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 2 milliseconds Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand clear took 4 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand add "USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3" Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13 Longing For Native Land.mp3","artist":"Yuhki Kuramoto","album":"SCENERIES IN LOVE","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/2001-04 Yuhki Kuramoto-Sceneries in Love from TV Drama Soundrtack/13 Longing For Native Land.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService stop Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus stop Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: No code Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 12 15:10:41 volumio volumio[1111]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 40ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 27ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 26ms Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: error: updateQueue error: null Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 24ms Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand add "USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3" took 22 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 4ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 3ms Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand play Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces system playlist update Jul 12 15:10:41 volumio volumio[1111]: info: Ignoring MPD Status Update Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 9ms Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand play took 7 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 7ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 7ms Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 3 milliseconds Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: Jul 12 15:10:41 volumio volumio[1111]: ---------------------------- MPD announces state update: player Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::getState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand status Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 8 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 5 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand status took 3 milliseconds Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseState Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus stop Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus play Jul 12 15:10:41 volumio volumio[1111]: info: Received an update from plugin. extracting info from payload Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus play Jul 12 15:10:41 volumio volumio[1111]: info: Received an update from plugin. extracting info from payload Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 37ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 39ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 38ms Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 12 15:10:41 volumio volumio[1111]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: verbose: ControllerMpd::parseTrackInfo Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus play Jul 12 15:10:41 volumio volumio[1111]: info: Received an update from plugin. extracting info from payload Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus play Jul 12 15:10:41 volumio volumio[1111]: info: Received an update from plugin. extracting info from payload Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: ControllerMpd::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::servicePushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":381,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Mirage On The Water","artist":"Yuhki Kuramoto","album":"Reminiscence","uri":"USB/music/newAge/Yuhki Kuramoto 유키구라모토 (1998 ~ 2010앨범16장)/1998-03 Yuhki Kuramoto-Reminiscence/Yuhki Kuramoto - A Mirage On The Water.mp3","trackType":"mp3"} Jul 12 15:10:41 volumio volumio[1111]: verbose: CURRENT POSITION 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState stateService play Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::syncState currentStatus play Jul 12 15:10:41 volumio volumio[1111]: info: Received an update from plugin. extracting info from payload Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: CoreStateMachine::pushState Jul 12 15:10:41 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:41 volumio volumio[1111]: info: CoreCommandRouter::volumioPushState Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 52ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 47ms Jul 12 15:10:41 volumio volumio[1111]: info: ------------------------------ 46ms Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:41 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:42 volumio volumio[1111]: info: Getting Spotify volume Jul 12 15:10:42 volumio volumio[1111]: (node:1111) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:42 volumio volumio[1111]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 12 15:10:42 volumio volumio[1111]: (node:1111) 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: 26) Jul 12 15:10:42 volumio volumio[1111]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 12 15:10:42 volumio volumio[1111]: info: CoreCommandRouter::volumioGetState Jul 12 15:10:42 volumio volumio[1111]: info: CorePlayQueue::getTrack 0 Jul 12 15:10:42 volumio volumio[1111]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 12 15:10:42 volumio volumio[1111]: info: Initializing connection to go-librespot Websocket Jul 12 15:10:42 volumio volumio[1111]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 12 15:10:42 volumio volumio[1111]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 15:10:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 12 15:10:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 808. Jul 12 15:10:42 volumio systemd[1]: Stopped go-librespot Daemon. Jul 12 15:10:42 volumio volumio[1111]: Error: self signed certificate in certificate chain Jul 12 15:10:42 volumio volumio[1111]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jul 12 15:10:42 volumio volumio[1111]: at TLSSocket.emit (events.js:315:20) Jul 12 15:10:42 volumio volumio[1111]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jul 12 15:10:42 volumio volumio[1111]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jul 12 15:10:42 volumio volumio[1111]: code: 'SELF_SIGNED_CERT_IN_CHAIN' Jul 12 15:10:42 volumio volumio[1111]: } Jul 12 15:10:42 volumio volumio[1111]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 15:10:42 volumio systemd[1]: Started go-librespot Daemon. Jul 12 15:10:42 volumio go-librespot[15206]: Librespot-go daemon starting... Jul 12 15:10:42 volumio go-librespot[15206]: time="2024-07-12T15:10:42+09:00" level=info msg="generated new device id: bd6e21a2df50a471510ba6270c25bb77abe76b85" Jul 12 15:10:42 volumio go-librespot[15206]: time="2024-07-12T15:10:42+09: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 signed by unknown authority" Jul 12 15:10:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 12 15:10:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 12 15:10:42 volumio sudo[15223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 15:09 Jul 12 15:10:42 volumio sudo[15223]: 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="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"