-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Fri 2025-04-18 20:09:48 MSK. -- Apr 18 20:08:00 volumio volumio[988]: info: Getting Spotify volume Apr 18 20:08:00 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:00 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 18 20:08:00 volumio volumio[988]: (node:988) 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: 11) Apr 18 20:08:00 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 18 20:08:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 18 20:08:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Apr 18 20:08:01 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:01 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 237. Apr 18 20:08:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:01 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:01 volumio go-librespot[5332]: go-librespot daemon starting... Apr 18 20:08:01 volumio go-librespot[5332]: time="2025-04-18T20:08:01+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:01 volumio go-librespot[5332]: time="2025-04-18T20:08:01+03:00" level=debug msg="app state loaded" Apr 18 20:08:01 volumio go-librespot[5332]: time="2025-04-18T20:08:01+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:01 volumio go-librespot[5332]: time="2025-04-18T20:08:01+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:02 volumio go-librespot[5332]: time="2025-04-18T20:08:02+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:02 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:02 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:02 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:04 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:04 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 238. Apr 18 20:08:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:05 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:05 volumio go-librespot[5344]: go-librespot daemon starting... Apr 18 20:08:05 volumio go-librespot[5344]: time="2025-04-18T20:08:05+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:05 volumio go-librespot[5344]: time="2025-04-18T20:08:05+03:00" level=debug msg="app state loaded" Apr 18 20:08:05 volumio go-librespot[5344]: time="2025-04-18T20:08:05+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:05 volumio go-librespot[5344]: time="2025-04-18T20:08:05+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:05 volumio go-librespot[5344]: time="2025-04-18T20:08:05+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:07 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:07 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 239. Apr 18 20:08:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:09 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:09 volumio go-librespot[5352]: go-librespot daemon starting... Apr 18 20:08:09 volumio go-librespot[5352]: time="2025-04-18T20:08:09+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:09 volumio go-librespot[5352]: time="2025-04-18T20:08:09+03:00" level=debug msg="app state loaded" Apr 18 20:08:09 volumio go-librespot[5352]: time="2025-04-18T20:08:09+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:09 volumio go-librespot[5352]: time="2025-04-18T20:08:09+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:09 volumio go-librespot[5352]: time="2025-04-18T20:08:09+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:10 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:10 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:12 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:12 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:12 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 240. Apr 18 20:08:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:12 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:12 volumio go-librespot[5374]: go-librespot daemon starting... Apr 18 20:08:12 volumio go-librespot[5374]: time="2025-04-18T20:08:12+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:12 volumio go-librespot[5374]: time="2025-04-18T20:08:12+03:00" level=debug msg="app state loaded" Apr 18 20:08:12 volumio go-librespot[5374]: time="2025-04-18T20:08:12+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:12 volumio go-librespot[5374]: time="2025-04-18T20:08:12+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:13 volumio go-librespot[5374]: time="2025-04-18T20:08:13+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:13 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:13 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:16 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:16 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 241. Apr 18 20:08:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:16 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:16 volumio go-librespot[5382]: go-librespot daemon starting... Apr 18 20:08:16 volumio go-librespot[5382]: time="2025-04-18T20:08:16+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:16 volumio go-librespot[5382]: time="2025-04-18T20:08:16+03:00" level=debug msg="app state loaded" Apr 18 20:08:16 volumio go-librespot[5382]: time="2025-04-18T20:08:16+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:16 volumio go-librespot[5382]: time="2025-04-18T20:08:16+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:17 volumio go-librespot[5382]: time="2025-04-18T20:08:17+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:19 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:19 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Apr 18 20:08:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:20 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:20 volumio go-librespot[5442]: go-librespot daemon starting... Apr 18 20:08:20 volumio go-librespot[5442]: time="2025-04-18T20:08:20+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:20 volumio go-librespot[5442]: time="2025-04-18T20:08:20+03:00" level=debug msg="app state loaded" Apr 18 20:08:20 volumio go-librespot[5442]: time="2025-04-18T20:08:20+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:20 volumio go-librespot[5442]: time="2025-04-18T20:08:20+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:20 volumio go-librespot[5442]: time="2025-04-18T20:08:20+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:22 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:22 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:22 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:23 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:23 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Apr 18 20:08:24 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:24 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:24 volumio go-librespot[5451]: go-librespot daemon starting... Apr 18 20:08:24 volumio go-librespot[5451]: time="2025-04-18T20:08:24+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:24 volumio go-librespot[5451]: time="2025-04-18T20:08:24+03:00" level=debug msg="app state loaded" Apr 18 20:08:24 volumio go-librespot[5451]: time="2025-04-18T20:08:24+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:24 volumio go-librespot[5451]: time="2025-04-18T20:08:24+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:24 volumio go-librespot[5451]: time="2025-04-18T20:08:24+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:25 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:25 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Apr 18 20:08:27 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:27 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:27 volumio go-librespot[5463]: go-librespot daemon starting... Apr 18 20:08:27 volumio go-librespot[5463]: time="2025-04-18T20:08:27+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:27 volumio go-librespot[5463]: time="2025-04-18T20:08:27+03:00" level=debug msg="app state loaded" Apr 18 20:08:27 volumio go-librespot[5463]: time="2025-04-18T20:08:27+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:27 volumio go-librespot[5463]: time="2025-04-18T20:08:27+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:28 volumio go-librespot[5463]: time="2025-04-18T20:08:28+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:28 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:28 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:31 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:31 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Apr 18 20:08:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:31 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:31 volumio go-librespot[5485]: go-librespot daemon starting... Apr 18 20:08:31 volumio go-librespot[5485]: time="2025-04-18T20:08:31+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:31 volumio go-librespot[5485]: time="2025-04-18T20:08:31+03:00" level=debug msg="app state loaded" Apr 18 20:08:31 volumio go-librespot[5485]: time="2025-04-18T20:08:31+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:31 volumio go-librespot[5485]: time="2025-04-18T20:08:31+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:32 volumio go-librespot[5485]: time="2025-04-18T20:08:32+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:33 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:33 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:33 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:34 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:34 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Apr 18 20:08:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:35 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:35 volumio go-librespot[5494]: go-librespot daemon starting... Apr 18 20:08:35 volumio go-librespot[5494]: time="2025-04-18T20:08:35+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:35 volumio go-librespot[5494]: time="2025-04-18T20:08:35+03:00" level=debug msg="app state loaded" Apr 18 20:08:35 volumio go-librespot[5494]: time="2025-04-18T20:08:35+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:35 volumio go-librespot[5494]: time="2025-04-18T20:08:35+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:35 volumio go-librespot[5494]: time="2025-04-18T20:08:35+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:37 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:37 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 247. Apr 18 20:08:39 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:39 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:39 volumio go-librespot[5503]: go-librespot daemon starting... Apr 18 20:08:39 volumio go-librespot[5503]: time="2025-04-18T20:08:39+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:39 volumio go-librespot[5503]: time="2025-04-18T20:08:39+03:00" level=debug msg="app state loaded" Apr 18 20:08:39 volumio go-librespot[5503]: time="2025-04-18T20:08:39+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:39 volumio go-librespot[5503]: time="2025-04-18T20:08:39+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:39 volumio go-librespot[5503]: time="2025-04-18T20:08:39+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:40 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:40 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 248. Apr 18 20:08:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:42 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:42 volumio go-librespot[5527]: go-librespot daemon starting... Apr 18 20:08:42 volumio go-librespot[5527]: time="2025-04-18T20:08:42+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:42 volumio go-librespot[5527]: time="2025-04-18T20:08:42+03:00" level=debug msg="app state loaded" Apr 18 20:08:42 volumio go-librespot[5527]: time="2025-04-18T20:08:42+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:42 volumio go-librespot[5527]: time="2025-04-18T20:08:42+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:43 volumio go-librespot[5527]: time="2025-04-18T20:08:43+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:43 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:43 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:43 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:43 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:43 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:46 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:46 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 249. Apr 18 20:08:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:46 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:46 volumio go-librespot[5539]: go-librespot daemon starting... Apr 18 20:08:46 volumio go-librespot[5539]: time="2025-04-18T20:08:46+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:46 volumio go-librespot[5539]: time="2025-04-18T20:08:46+03:00" level=debug msg="app state loaded" Apr 18 20:08:46 volumio go-librespot[5539]: time="2025-04-18T20:08:46+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:46 volumio go-librespot[5539]: time="2025-04-18T20:08:46+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:47 volumio go-librespot[5539]: time="2025-04-18T20:08:47+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:49 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:49 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 250. Apr 18 20:08:50 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:50 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:50 volumio go-librespot[5562]: go-librespot daemon starting... Apr 18 20:08:50 volumio go-librespot[5562]: time="2025-04-18T20:08:50+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:50 volumio go-librespot[5562]: time="2025-04-18T20:08:50+03:00" level=debug msg="app state loaded" Apr 18 20:08:50 volumio go-librespot[5562]: time="2025-04-18T20:08:50+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:50 volumio go-librespot[5562]: time="2025-04-18T20:08:50+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:50 volumio go-librespot[5562]: time="2025-04-18T20:08:50+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:52 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:52 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:53 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:08:53 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:08:53 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:08:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 251. Apr 18 20:08:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:54 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:54 volumio go-librespot[5571]: go-librespot daemon starting... Apr 18 20:08:54 volumio go-librespot[5571]: time="2025-04-18T20:08:54+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:54 volumio go-librespot[5571]: time="2025-04-18T20:08:54+03:00" level=debug msg="app state loaded" Apr 18 20:08:54 volumio go-librespot[5571]: time="2025-04-18T20:08:54+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:54 volumio go-librespot[5571]: time="2025-04-18T20:08:54+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:54 volumio go-librespot[5571]: time="2025-04-18T20:08:54+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:55 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:55 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:08:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:08:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 252. Apr 18 20:08:57 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:08:57 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:08:57 volumio go-librespot[5580]: go-librespot daemon starting... Apr 18 20:08:57 volumio go-librespot[5580]: time="2025-04-18T20:08:57+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:08:57 volumio go-librespot[5580]: time="2025-04-18T20:08:57+03:00" level=debug msg="app state loaded" Apr 18 20:08:57 volumio go-librespot[5580]: time="2025-04-18T20:08:57+03:00" level=debug msg="stored credentials not found" Apr 18 20:08:57 volumio go-librespot[5580]: time="2025-04-18T20:08:57+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:08:58 volumio go-librespot[5580]: time="2025-04-18T20:08:58+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:08:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:08:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:08:58 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:08:58 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:01 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:01 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Apr 18 20:09:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:01 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:01 volumio go-librespot[5604]: go-librespot daemon starting... Apr 18 20:09:01 volumio go-librespot[5604]: time="2025-04-18T20:09:01+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:01 volumio go-librespot[5604]: time="2025-04-18T20:09:01+03:00" level=debug msg="app state loaded" Apr 18 20:09:01 volumio go-librespot[5604]: time="2025-04-18T20:09:01+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:01 volumio go-librespot[5604]: time="2025-04-18T20:09:01+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:02 volumio go-librespot[5604]: time="2025-04-18T20:09:02+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:03 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:09:04 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:09:04 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:09:04 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:04 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254. Apr 18 20:09:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:05 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:05 volumio go-librespot[5616]: go-librespot daemon starting... Apr 18 20:09:05 volumio go-librespot[5616]: time="2025-04-18T20:09:05+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:05 volumio go-librespot[5616]: time="2025-04-18T20:09:05+03:00" level=debug msg="app state loaded" Apr 18 20:09:05 volumio go-librespot[5616]: time="2025-04-18T20:09:05+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:05 volumio go-librespot[5616]: time="2025-04-18T20:09:05+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:05 volumio go-librespot[5616]: time="2025-04-18T20:09:05+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:07 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:07 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255. Apr 18 20:09:08 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:08 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:08 volumio go-librespot[5624]: go-librespot daemon starting... Apr 18 20:09:08 volumio go-librespot[5624]: time="2025-04-18T20:09:08+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:08 volumio go-librespot[5624]: time="2025-04-18T20:09:08+03:00" level=debug msg="app state loaded" Apr 18 20:09:08 volumio go-librespot[5624]: time="2025-04-18T20:09:08+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:08 volumio go-librespot[5624]: time="2025-04-18T20:09:08+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:09 volumio go-librespot[5624]: time="2025-04-18T20:09:09+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:10 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:10 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256. Apr 18 20:09:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:12 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:12 volumio go-librespot[5646]: go-librespot daemon starting... Apr 18 20:09:12 volumio go-librespot[5646]: time="2025-04-18T20:09:12+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:12 volumio go-librespot[5646]: time="2025-04-18T20:09:12+03:00" level=debug msg="app state loaded" Apr 18 20:09:12 volumio go-librespot[5646]: time="2025-04-18T20:09:12+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:12 volumio go-librespot[5646]: time="2025-04-18T20:09:12+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:13 volumio go-librespot[5646]: time="2025-04-18T20:09:13+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:13 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:13 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:14 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:09:14 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:09:14 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257. Apr 18 20:09:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:16 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:16 volumio go-librespot[5654]: go-librespot daemon starting... Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=debug msg="app state loaded" Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:16 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=debug msg="new websocket client" Apr 18 20:09:16 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 18 20:09:16 volumio go-librespot[5654]: time="2025-04-18T20:09:16+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:16 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 18 20:09:19 volumio volumio[988]: info: Getting Spotify volume Apr 18 20:09:19 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:19 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 18 20:09:19 volumio volumio[988]: (node:988) 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: 12) Apr 18 20:09:19 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 18 20:09:19 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 18 20:09:19 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Apr 18 20:09:19 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:19 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258. Apr 18 20:09:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:20 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:20 volumio go-librespot[5701]: go-librespot daemon starting... Apr 18 20:09:20 volumio go-librespot[5701]: time="2025-04-18T20:09:20+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:20 volumio go-librespot[5701]: time="2025-04-18T20:09:20+03:00" level=debug msg="app state loaded" Apr 18 20:09:20 volumio go-librespot[5701]: time="2025-04-18T20:09:20+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:20 volumio go-librespot[5701]: time="2025-04-18T20:09:20+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:20 volumio go-librespot[5701]: time="2025-04-18T20:09:20+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:22 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:22 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259. Apr 18 20:09:23 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:23 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:23 volumio go-librespot[5723]: go-librespot daemon starting... Apr 18 20:09:23 volumio go-librespot[5723]: time="2025-04-18T20:09:23+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:23 volumio go-librespot[5723]: time="2025-04-18T20:09:23+03:00" level=debug msg="app state loaded" Apr 18 20:09:23 volumio go-librespot[5723]: time="2025-04-18T20:09:23+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:23 volumio go-librespot[5723]: time="2025-04-18T20:09:23+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:24 volumio go-librespot[5723]: time="2025-04-18T20:09:24+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:24 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:09:24 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:09:24 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:09:25 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:25 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 260. Apr 18 20:09:27 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:27 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:27 volumio go-librespot[5734]: go-librespot daemon starting... Apr 18 20:09:27 volumio go-librespot[5734]: time="2025-04-18T20:09:27+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:27 volumio go-librespot[5734]: time="2025-04-18T20:09:27+03:00" level=debug msg="app state loaded" Apr 18 20:09:27 volumio go-librespot[5734]: time="2025-04-18T20:09:27+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:27 volumio go-librespot[5734]: time="2025-04-18T20:09:27+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:28 volumio go-librespot[5734]: time="2025-04-18T20:09:28+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:28 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:28 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 261. Apr 18 20:09:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:31 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:31 volumio go-librespot[5756]: go-librespot daemon starting... Apr 18 20:09:31 volumio go-librespot[5756]: time="2025-04-18T20:09:31+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:31 volumio go-librespot[5756]: time="2025-04-18T20:09:31+03:00" level=debug msg="app state loaded" Apr 18 20:09:31 volumio go-librespot[5756]: time="2025-04-18T20:09:31+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:31 volumio go-librespot[5756]: time="2025-04-18T20:09:31+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:31 volumio go-librespot[5756]: time="2025-04-18T20:09:31+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:31 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:31 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 262. Apr 18 20:09:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:34 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:34 volumio go-librespot[5766]: go-librespot daemon starting... Apr 18 20:09:34 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:34 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:34 volumio go-librespot[5766]: time="2025-04-18T20:09:34+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:34 volumio go-librespot[5766]: time="2025-04-18T20:09:34+03:00" level=debug msg="app state loaded" Apr 18 20:09:34 volumio go-librespot[5766]: time="2025-04-18T20:09:34+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:34 volumio go-librespot[5766]: time="2025-04-18T20:09:34+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:34 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:09:34 volumio sudo[5774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 20:09:34 volumio sudo[5774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 20:09:34 volumio sudo[5774]: pam_unix(sudo:session): session closed for user root Apr 18 20:09:34 volumio sudo[5777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 20:09:34 volumio sudo[5777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 20:09:35 volumio sudo[5777]: pam_unix(sudo:session): session closed for user root Apr 18 20:09:35 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.1.113 from 192.168.1.107 UA: Mozilla/5.0 (Linux; arm_64; Android 12; JAD-LX9) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.6834.96 YaBrowser/25.2.6.96.00 SA/3 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 18 20:09:35 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 20:09:35 volumio volumio[988]: info: Listing playlists Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 18 20:09:35 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 20:09:35 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:09:35 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:09:35 volumio go-librespot[5766]: time="2025-04-18T20:09:35+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:36 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 20:09:36 volumio volumio[988]: info: Received Get System Info Apr 18 20:09:36 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 20:09:36 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 20:09:36 volumio volumio[988]: info: Discovery: Getting this device information Apr 18 20:09:36 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 18 20:09:36 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Apr 18 20:09:36 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 20:09:37 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Apr 18 20:09:37 volumio volumio[988]: info: CoreStateMachine::getQueue Apr 18 20:09:37 volumio volumio[988]: info: CorePlayQueue::getQueue Apr 18 20:09:37 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:37 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 263. Apr 18 20:09:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:38 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:38 volumio go-librespot[5782]: go-librespot daemon starting... Apr 18 20:09:38 volumio go-librespot[5782]: time="2025-04-18T20:09:38+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:38 volumio go-librespot[5782]: time="2025-04-18T20:09:38+03:00" level=debug msg="app state loaded" Apr 18 20:09:38 volumio go-librespot[5782]: time="2025-04-18T20:09:38+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:38 volumio go-librespot[5782]: time="2025-04-18T20:09:38+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:39 volumio go-librespot[5782]: time="2025-04-18T20:09:39+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:40 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:40 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 264. Apr 18 20:09:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:42 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:42 volumio go-librespot[5804]: go-librespot daemon starting... Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio volumio[988]: info: Retrieving Cloud Streaming UI Apr 18 20:09:42 volumio volumio[988]: info: Getting Tidal Cloud Configuration Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio volumio[988]: info: Getting Qobuz Cloud Configuration Apr 18 20:09:42 volumio volumio[988]: info: Asking plugin for UI Config Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio go-librespot[5804]: time="2025-04-18T20:09:42+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:42 volumio go-librespot[5804]: time="2025-04-18T20:09:42+03:00" level=debug msg="app state loaded" Apr 18 20:09:42 volumio go-librespot[5804]: time="2025-04-18T20:09:42+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:42 volumio volumio[988]: info: Getting Spotify Cloud Configuration Apr 18 20:09:42 volumio volumio[988]: info: Asking plugin for UI Config Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio go-librespot[5804]: time="2025-04-18T20:09:42+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:42 volumio volumio[988]: info: Saving Spotify Acccount Apr 18 20:09:42 volumio volumio[988]: info: Got Tidal Cloud Configuration Apr 18 20:09:42 volumio volumio[988]: info: Got it Apr 18 20:09:42 volumio volumio[988]: info: Got it Apr 18 20:09:42 volumio volumio[988]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 20:09:42 volumio volumio[988]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 20:09:42 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 18 20:09:42 volumio go-librespot[5804]: time="2025-04-18T20:09:42+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:43 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:43 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:45 volumio volumio[988]: info: MyVolumio login type: Token Apr 18 20:09:45 volumio volumio[988]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 18 20:09:45 volumio volumio[988]: info: Retrying Login Due to Network Error Apr 18 20:09:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 18 20:09:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 265. Apr 18 20:09:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 18 20:09:46 volumio systemd[1]: Started go-librespot Daemon. Apr 18 20:09:46 volumio go-librespot[5815]: go-librespot daemon starting... Apr 18 20:09:46 volumio go-librespot[5815]: time="2025-04-18T20:09:46+03:00" level=info msg="running go-librespot 0.2.0" Apr 18 20:09:46 volumio go-librespot[5815]: time="2025-04-18T20:09:46+03:00" level=debug msg="app state loaded" Apr 18 20:09:46 volumio go-librespot[5815]: time="2025-04-18T20:09:46+03:00" level=debug msg="stored credentials not found" Apr 18 20:09:46 volumio go-librespot[5815]: time="2025-04-18T20:09:46+03:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 18 20:09:46 volumio go-librespot[5815]: time="2025-04-18T20:09:46+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Apr 18 20:09:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 18 20:09:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 18 20:09:46 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 20:09:46 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 18 20:09:46 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 18 20:09:48 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 18 20:09:48 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 18 20:09:48 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 20:09:48 volumio volumio[988]: Error: getaddrinfo -3007 Apr 18 20:09:48 volumio volumio[988]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 18 20:09:48 volumio volumio[988]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 18 20:09:48 volumio volumio[988]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 18 20:09:48 volumio volumio[988]: code: -3007, Apr 18 20:09:48 volumio volumio[988]: errno: -3007, Apr 18 20:09:48 volumio volumio[988]: syscall: 'getaddrinfo' Apr 18 20:09:48 volumio volumio[988]: } Apr 18 20:09:48 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 20:09:48 volumio sudo[5834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 20:08 Apr 18 20:09:48 volumio sudo[5834]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"