-- Logs begin at Thu 2024-12-19 11:45:02 CET, end at Wed 2025-03-05 12:58:08 CET. -- Mar 05 12:57:00 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:00 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:01 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:01 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:01 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:01 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:02 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:02 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1769. Mar 05 12:57:03 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:03 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:03 volumio go-librespot[29721]: Librespot-go daemon starting... Mar 05 12:57:03 volumio go-librespot[29721]: time="2025-03-05T12:57:03+01:00" level=info msg="generated new device id: 15534282ed6d00f5fdd4e4080db38fe6fffb7e14" Mar 05 12:57:03 volumio go-librespot[29721]: time="2025-03-05T12:57:03+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:03 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:03 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:04 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:04 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:04 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:04 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:05 volumio volumio[1038]: info: Mar 05 12:57:05 volumio volumio[1038]: ---------------------------- Client requests Volumio toggle Mar 05 12:57:05 volumio volumio[1038]: info: CoreCommandRouter::volumioToggle Mar 05 12:57:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:05 volumio volumio[1038]: info: CoreStateMachine::play index undefined Mar 05 12:57:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:57:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:05 volumio volumio[1038]: info: CoreStateMachine::startPlaybackTimer Mar 05 12:57:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:05 volumio volumio[1038]: verbose: MPD COMMAND [object Object] Mar 05 12:57:05 volumio volumio[1038]: verbose: MPD COMMAND [object Object] Mar 05 12:57:05 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1770. Mar 05 12:57:06 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:06 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:06 volumio go-librespot[29781]: Librespot-go daemon starting... Mar 05 12:57:06 volumio go-librespot[29781]: time="2025-03-05T12:57:06+01:00" level=info msg="generated new device id: 4f2434fcac4e7e5688f3ea8c520e571f08dc2fe0" Mar 05 12:57:06 volumio go-librespot[29781]: time="2025-03-05T12:57:06+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:06 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/95/12/29/9512290a-58cb-5d0e-168b-9a0cfe2dd50b/source/600x600bb.jpg","name":"Janet Jackson - All for You","title":"All for You","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"289","seek":51852,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 05 12:57:06 volumio volumio[1038]: verbose: CURRENT POSITION 0 Mar 05 12:57:06 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Mar 05 12:57:06 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus pause Mar 05 12:57:06 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:57:06 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:57:06 volumio volumio[1038]: info: Mar 05 12:57:06 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:06 volumio volumio[1038]: info: Mar 05 12:57:06 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:06 volumio volumio[1038]: info: Mar 05 12:57:06 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand status took 3 milliseconds Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand status took 2 milliseconds Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand status took 3 milliseconds Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand clearerror took 3 milliseconds Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand clearerror took 3 milliseconds Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 05 12:57:06 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:06 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:06 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:06 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:06 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:06 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:06 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:06 volumio volumio[1038]: info: ------------------------------ 7ms Mar 05 12:57:06 volumio volumio[1038]: info: ------------------------------ 7ms Mar 05 12:57:06 volumio volumio[1038]: info: ------------------------------ 6ms Mar 05 12:57:07 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:07 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:07 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:08 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:08 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:09 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:09 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1771. Mar 05 12:57:09 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:09 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:09 volumio go-librespot[29795]: Librespot-go daemon starting... Mar 05 12:57:09 volumio go-librespot[29795]: time="2025-03-05T12:57:09+01:00" level=info msg="generated new device id: f176ab1520ccf2ea1d2585cb9bbfe419dd0e04b9" Mar 05 12:57:09 volumio go-librespot[29795]: time="2025-03-05T12:57:09+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:10 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:10 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:10 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:10 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:11 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:11 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:12 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:12 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1772. Mar 05 12:57:12 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:12 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:12 volumio go-librespot[29803]: Librespot-go daemon starting... Mar 05 12:57:12 volumio go-librespot[29803]: time="2025-03-05T12:57:12+01:00" level=info msg="generated new device id: e4124b14ec4686499d4b06434db04e9da83be438" Mar 05 12:57:12 volumio go-librespot[29803]: time="2025-03-05T12:57:12+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:13 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:13 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:13 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:13 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:14 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:15 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:15 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1773. Mar 05 12:57:16 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:16 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:16 volumio go-librespot[29825]: Librespot-go daemon starting... Mar 05 12:57:16 volumio go-librespot[29825]: time="2025-03-05T12:57:16+01:00" level=info msg="generated new device id: 4fe6cc77ebf132e0dad69b2752490a3b709b28fb" Mar 05 12:57:16 volumio go-librespot[29825]: time="2025-03-05T12:57:16+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:16 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:16 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:16 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:16 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:17 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:17 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:18 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:18 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1774. Mar 05 12:57:19 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:19 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:19 volumio go-librespot[29837]: Librespot-go daemon starting... Mar 05 12:57:19 volumio go-librespot[29837]: time="2025-03-05T12:57:19+01:00" level=info msg="generated new device id: 9b28a4c33097cfaaa186a1909cf6327fa140adec" Mar 05 12:57:19 volumio go-librespot[29837]: time="2025-03-05T12:57:19+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:19 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:19 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:19 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:19 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:20 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:20 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:21 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:21 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:22 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:22 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1775. Mar 05 12:57:22 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:22 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:22 volumio go-librespot[29845]: Librespot-go daemon starting... Mar 05 12:57:22 volumio go-librespot[29845]: time="2025-03-05T12:57:22+01:00" level=info msg="generated new device id: 7c3f16b44d80ff39de9ad11eefed25cddd14fed3" Mar 05 12:57:22 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:22 volumio go-librespot[29845]: time="2025-03-05T12:57:22+01:00" level=debug msg="new websocket client" Mar 05 12:57:22 volumio volumio[1038]: info: Connection to go-librespot Websocket established Mar 05 12:57:22 volumio go-librespot[29845]: time="2025-03-05T12:57:22+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:22 volumio volumio[1038]: info: Connection to go-librespot Websocket closed Mar 05 12:57:23 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:23 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:24 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:24 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:25 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:25 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:25 volumio volumio[1038]: info: Getting Spotify volume Mar 05 12:57:25 volumio volumio[1038]: (node:1038) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:25 volumio volumio[1038]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 05 12:57:25 volumio volumio[1038]: (node:1038) 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: 19) Mar 05 12:57:25 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 05 12:57:25 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:25 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:25 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:25 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1776. Mar 05 12:57:25 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:25 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:25 volumio go-librespot[29867]: Librespot-go daemon starting... Mar 05 12:57:25 volumio go-librespot[29867]: time="2025-03-05T12:57:25+01:00" level=info msg="generated new device id: 0af5ea7fa606c6f8c012843a18749efadcd7963b" Mar 05 12:57:25 volumio go-librespot[29867]: time="2025-03-05T12:57:25+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:26 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:26 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:27 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:27 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:28 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:28 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:28 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:28 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1777. Mar 05 12:57:29 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:29 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:29 volumio go-librespot[29878]: Librespot-go daemon starting... Mar 05 12:57:29 volumio go-librespot[29878]: time="2025-03-05T12:57:29+01:00" level=info msg="generated new device id: c7c628abc68eb22e54d55c8aeaa69c50586f2e77" Mar 05 12:57:29 volumio go-librespot[29878]: time="2025-03-05T12:57:29+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:29 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:29 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:30 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:30 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: info: Mar 05 12:57:31 volumio volumio[1038]: ---------------------------- Client requests Volumio toggle Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::volumioToggle Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::pause Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::servicePause Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::servicePause Mar 05 12:57:31 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand pause Mar 05 12:57:31 volumio volumio[1038]: info: sendMpdCommand pause took 1 milliseconds Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/95/12/29/9512290a-58cb-5d0e-168b-9a0cfe2dd50b/source/600x600bb.jpg","name":"Janet Jackson - All for You","title":"All for You","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"289","seek":77368,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 05 12:57:31 volumio volumio[1038]: verbose: CURRENT POSITION 0 Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::syncState stateService pause Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus pause Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:57:31 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Mar 05 12:57:31 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:31 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:31 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1778. Mar 05 12:57:32 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:32 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:32 volumio go-librespot[29887]: Librespot-go daemon starting... Mar 05 12:57:32 volumio go-librespot[29887]: time="2025-03-05T12:57:32+01:00" level=info msg="generated new device id: 00a32de35856cdc27980b908dff085d0eb785da9" Mar 05 12:57:32 volumio go-librespot[29887]: time="2025-03-05T12:57:32+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:32 volumio volumio[1038]: info: Mar 05 12:57:32 volumio volumio[1038]: ---------------------------- Client requests Volumio toggle Mar 05 12:57:32 volumio volumio[1038]: info: CoreCommandRouter::volumioToggle Mar 05 12:57:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:32 volumio volumio[1038]: info: CoreStateMachine::play index undefined Mar 05 12:57:32 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:57:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:32 volumio volumio[1038]: info: CoreStateMachine::startPlaybackTimer Mar 05 12:57:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:32 volumio volumio[1038]: verbose: MPD COMMAND [object Object] Mar 05 12:57:32 volumio volumio[1038]: verbose: MPD COMMAND [object Object] Mar 05 12:57:32 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: info: Mar 05 12:57:33 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/95/12/29/9512290a-58cb-5d0e-168b-9a0cfe2dd50b/source/600x600bb.jpg","name":"Janet Jackson - All for You","title":"All for You","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"289","seek":77368,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 05 12:57:33 volumio volumio[1038]: verbose: CURRENT POSITION 0 Mar 05 12:57:33 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Mar 05 12:57:33 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus pause Mar 05 12:57:33 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:57:33 volumio volumio[1038]: info: Mar 05 12:57:33 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:33 volumio volumio[1038]: info: Mar 05 12:57:33 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand status took 7 milliseconds Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand status took 3 milliseconds Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand status took 2 milliseconds Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand clearerror took 5 milliseconds Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand clearerror took 5 milliseconds Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand clearerror took 5 milliseconds Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 05 12:57:33 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:33 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:33 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:33 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:57:33 volumio volumio[1038]: info: ------------------------------ 13ms Mar 05 12:57:33 volumio volumio[1038]: info: ------------------------------ 8ms Mar 05 12:57:33 volumio volumio[1038]: info: ------------------------------ 7ms Mar 05 12:57:33 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:33 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:34 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:34 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:34 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:34 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1779. Mar 05 12:57:35 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:35 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:35 volumio go-librespot[29911]: Librespot-go daemon starting... Mar 05 12:57:35 volumio go-librespot[29911]: time="2025-03-05T12:57:35+01:00" level=info msg="generated new device id: 309a0b2f293fdbc9a296c836b8b0423ef144f337" Mar 05 12:57:35 volumio go-librespot[29911]: time="2025-03-05T12:57:35+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:35 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:35 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:36 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:36 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:37 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:37 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:37 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:37 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1780. Mar 05 12:57:38 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:38 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:38 volumio go-librespot[29922]: Librespot-go daemon starting... Mar 05 12:57:38 volumio go-librespot[29922]: time="2025-03-05T12:57:38+01:00" level=info msg="generated new device id: 9566c26c444f58dfc0e9751e878e59a901d6c745" Mar 05 12:57:38 volumio go-librespot[29922]: time="2025-03-05T12:57:38+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:38 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:38 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:40 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:40 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:40 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:40 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:41 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:41 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1781. Mar 05 12:57:42 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:42 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:42 volumio go-librespot[29931]: Librespot-go daemon starting... Mar 05 12:57:42 volumio go-librespot[29931]: time="2025-03-05T12:57:42+01:00" level=info msg="generated new device id: ade9019de3cf3579a0aa06aeeaec25b28db1afbc" Mar 05 12:57:42 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:42 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:42 volumio go-librespot[29931]: time="2025-03-05T12:57:42+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:43 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:43 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:43 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:43 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:44 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:44 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:45 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:45 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1782. Mar 05 12:57:45 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:45 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:45 volumio go-librespot[29953]: Librespot-go daemon starting... Mar 05 12:57:45 volumio go-librespot[29953]: time="2025-03-05T12:57:45+01:00" level=info msg="generated new device id: 75bc5a1aeeb60a6c13acd1bed075bf3e86803cde" Mar 05 12:57:45 volumio go-librespot[29953]: time="2025-03-05T12:57:45+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:46 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:46 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:46 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:46 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:47 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:47 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:48 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:48 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1783. Mar 05 12:57:48 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:48 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:48 volumio go-librespot[29965]: Librespot-go daemon starting... Mar 05 12:57:48 volumio go-librespot[29965]: time="2025-03-05T12:57:48+01:00" level=info msg="generated new device id: 18836b875e3801fb144c36865e11fec0fa35eb79" Mar 05 12:57:48 volumio go-librespot[29965]: time="2025-03-05T12:57:48+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:49 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:49 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:49 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:49 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:50 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:50 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:51 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:51 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1784. Mar 05 12:57:51 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:51 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:51 volumio go-librespot[29974]: Librespot-go daemon starting... Mar 05 12:57:51 volumio go-librespot[29974]: time="2025-03-05T12:57:51+01:00" level=info msg="generated new device id: fb4516d7867f16661f7afdae069bb489c834eaa8" Mar 05 12:57:51 volumio go-librespot[29974]: time="2025-03-05T12:57:51+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:52 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:52 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:52 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:52 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:53 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:53 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:54 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:54 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1785. Mar 05 12:57:55 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:55 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:55 volumio go-librespot[29996]: Librespot-go daemon starting... Mar 05 12:57:55 volumio go-librespot[29996]: time="2025-03-05T12:57:55+01:00" level=info msg="generated new device id: 6f93bcc5ddb89cb7640d09a026ac0b18d8fa72fe" Mar 05 12:57:55 volumio go-librespot[29996]: time="2025-03-05T12:57:55+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:55 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:55 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:55 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:55 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:56 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:56 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:57 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:57:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1786. Mar 05 12:57:58 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:57:58 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:57:58 volumio go-librespot[30007]: Librespot-go daemon starting... Mar 05 12:57:58 volumio go-librespot[30007]: time="2025-03-05T12:57:58+01:00" level=info msg="generated new device id: 67fbdaf59949ae7f2e968571ce4196afe073f511" Mar 05 12:57:58 volumio go-librespot[30007]: time="2025-03-05T12:57:58+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:57:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:57:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:57:58 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:58 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:57:58 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:57:58 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:57:59 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:57:59 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:00 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:00 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:58:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1787. Mar 05 12:58:01 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:58:01 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:58:01 volumio go-librespot[30016]: Librespot-go daemon starting... Mar 05 12:58:01 volumio go-librespot[30016]: time="2025-03-05T12:58:01+01:00" level=info msg="generated new device id: ee856bcdf8ba6b6eea206137829b12a0c3cc7ef4" Mar 05 12:58:01 volumio go-librespot[30016]: time="2025-03-05T12:58:01+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:58:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:58:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:58:01 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:01 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:01 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:58:01 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:58:02 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:02 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:03 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:03 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:04 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:58:04 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:58:04 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:04 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:58:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1788. Mar 05 12:58:04 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:58:04 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:58:04 volumio go-librespot[30039]: Librespot-go daemon starting... Mar 05 12:58:04 volumio go-librespot[30039]: time="2025-03-05T12:58:04+01:00" level=info msg="generated new device id: 6b543ebcfccf759d8a960aee0413f1eb9c0c0ca7" Mar 05 12:58:04 volumio go-librespot[30039]: time="2025-03-05T12:58:04+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:58:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:58:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:58:05 volumio volumio[1038]: info: Airplay started streaming, receiving metadatas Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::updateTrackBlock Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrackBlock Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::serviceStop Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::serviceStop Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::stop Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:58:05 volumio volumio[1038]: info: Airplay started streaming, receiving metadatas Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:58:05 volumio volumio[1038]: info: Airplay started streaming, receiving metadatas Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:58:05 volumio volumio[1038]: info: Mar 05 12:58:05 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand stop took 5 milliseconds Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/95/12/29/9512290a-58cb-5d0e-168b-9a0cfe2dd50b/source/600x600bb.jpg","name":"Janet Jackson - All for You","title":"All for You","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"289","seek":77368,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 05 12:58:05 volumio volumio[1038]: verbose: CURRENT POSITION 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::syncState stateService stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: No code Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio shairport-sync[12617]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 05 12:58:05 volumio shairport-sync[12617]: Dload Upload Total Spent Left Speed Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand status took 10 milliseconds Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:58:05 volumio volumio[1038]: info: Mar 05 12:58:05 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:58:05 volumio volumio[1038]: info: Mar 05 12:58:05 volumio volumio[1038]: ---------------------------- MPD announces state update: player Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::getState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand status took 1 milliseconds Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseState Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:58:05 volumio volumio[1038]: info: ------------------------------ 15ms Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 05 12:58:05 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:58:05 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:58:05 volumio volumio[1038]: info: ControllerMpd::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 05 12:58:05 volumio volumio[1038]: info: ------------------------------ 9ms Mar 05 12:58:05 volumio volumio[1038]: info: ------------------------------ 9ms Mar 05 12:58:05 volumio volumio[1038]: info: Mar 05 12:58:05 volumio volumio[1038]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 05 12:58:05 volumio volumio[1038]: info: Airplay playback start Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::stop Mar 05 12:58:05 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 12:58:05 volumio shairport-sync[12617]: [158B blob data] Mar 05 12:58:05 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:05 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:06 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: Initializing connection to go-librespot Websocket Mar 05 12:58:07 volumio volumio[1038]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 05 12:58:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1789. Mar 05 12:58:08 volumio systemd[1]: Stopped go-librespot Daemon. Mar 05 12:58:08 volumio systemd[1]: Started go-librespot Daemon. Mar 05 12:58:08 volumio go-librespot[30101]: Librespot-go daemon starting... Mar 05 12:58:08 volumio go-librespot[30101]: time="2025-03-05T12:58:08+01:00" level=info msg="generated new device id: ebced968f4def57922de84a2c3790689b33162f8" Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio go-librespot[30101]: time="2025-03-05T12:58:08+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate signed by unknown authority" Mar 05 12:58:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 05 12:58:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreStateMachine::pushState Mar 05 12:58:08 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Mar 05 12:58:08 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 12:58:08 volumio volumio[1038]: Error: unable to verify the first certificate Mar 05 12:58:08 volumio volumio[1038]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Mar 05 12:58:08 volumio volumio[1038]: at TLSSocket.emit (events.js:315:20) Mar 05 12:58:08 volumio volumio[1038]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Mar 05 12:58:08 volumio volumio[1038]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Mar 05 12:58:08 volumio volumio[1038]: code: 'UNABLE_TO_VERIFY_LEAF_SIGNATURE' Mar 05 12:58:08 volumio volumio[1038]: } Mar 05 12:58:08 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 12:58:08 volumio sudo[30119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 12:57 Mar 05 12:58:08 volumio sudo[30119]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"