-- Logs begin at Wed 2025-02-26 18:25:46 MSK, end at Wed 2025-02-26 20:38:34 MSK. -- Feb 26 20:37:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154216. Feb 26 20:37:01 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:01 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:01 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:01 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:01 volumio go-librespot[17915]: Librespot-go daemon starting... Feb 26 20:37:01 volumio go-librespot[17915]: time="2025-02-26T20:37:01+03:00" level=info msg="generated new device id: c2cf8cf13af4e3426b0f7542ea9376700cb45417" Feb 26 20:37:01 volumio go-librespot[17915]: time="2025-02-26T20:37:01+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:04 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:04 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:04 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:04 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:04 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:04 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:04 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:04 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:04 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:04 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:04 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:04 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:04 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:04 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154217. Feb 26 20:37:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:04 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:04 volumio go-librespot[17958]: Librespot-go daemon starting... Feb 26 20:37:04 volumio go-librespot[17958]: time="2025-02-26T20:37:04+03:00" level=info msg="generated new device id: 65a8fc84540c05443224cefd7afb01c7f221b50a" Feb 26 20:37:04 volumio go-librespot[17958]: time="2025-02-26T20:37:04+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:05 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:06 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:06 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:07 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:07 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154218. Feb 26 20:37:07 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:07 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:07 volumio go-librespot[17970]: Librespot-go daemon starting... Feb 26 20:37:07 volumio go-librespot[17970]: time="2025-02-26T20:37:07+03:00" level=info msg="generated new device id: 99b0baeddbacfa841301dd8887f0aae5fcc5e448" Feb 26 20:37:08 volumio go-librespot[17970]: time="2025-02-26T20:37:08+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:10 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:10 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154219. Feb 26 20:37:11 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:11 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:11 volumio go-librespot[17983]: Librespot-go daemon starting... Feb 26 20:37:11 volumio go-librespot[17983]: time="2025-02-26T20:37:11+03:00" level=info msg="generated new device id: 22d7a9a2203e7a0dace3e48a47ef42cb22559554" Feb 26 20:37:11 volumio go-librespot[17983]: time="2025-02-26T20:37:11+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:13 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:13 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:14 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:14 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:14 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:14 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:14 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:14 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:14 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:14 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:14 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:14 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:14 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:14 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:14 volumio volumio[854]: info: Listing playlists Feb 26 20:37:14 volumio volumio[854]: info: Listing playlists Feb 26 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154220. Feb 26 20:37:14 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:14 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:14 volumio go-librespot[18025]: Librespot-go daemon starting... Feb 26 20:37:14 volumio go-librespot[18025]: time="2025-02-26T20:37:14+03:00" level=info msg="generated new device id: 1c75eb7a9c4723f718a4fd77dede4532819c9fda" Feb 26 20:37:14 volumio go-librespot[18025]: time="2025-02-26T20:37:14+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:16 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:16 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:16 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:16 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:16 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154221. Feb 26 20:37:17 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:17 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:17 volumio go-librespot[18040]: Librespot-go daemon starting... Feb 26 20:37:17 volumio go-librespot[18040]: time="2025-02-26T20:37:17+03:00" level=info msg="generated new device id: 81d7e0dbd5a0e70b359bad2096ef8f8fd59be5f0" Feb 26 20:37:18 volumio go-librespot[18040]: time="2025-02-26T20:37:18+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:19 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:19 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154222. Feb 26 20:37:21 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:21 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:21 volumio go-librespot[18053]: Librespot-go daemon starting... Feb 26 20:37:21 volumio go-librespot[18053]: time="2025-02-26T20:37:21+03:00" level=info msg="generated new device id: a19630d9417c5cf087a256108463bbb5b509b120" Feb 26 20:37:21 volumio go-librespot[18053]: time="2025-02-26T20:37:21+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:22 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:22 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:24 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:24 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:24 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:24 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:24 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:24 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:24 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:24 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:24 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:24 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:24 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:24 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:24 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154223. Feb 26 20:37:24 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:24 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:24 volumio go-librespot[18096]: Librespot-go daemon starting... Feb 26 20:37:24 volumio go-librespot[18096]: time="2025-02-26T20:37:24+03:00" level=info msg="generated new device id: 287933c6db4d9b7263b949cc8428cbff97988e1d" Feb 26 20:37:25 volumio go-librespot[18096]: time="2025-02-26T20:37:25+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:25 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:25 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:26 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:26 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:26 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154224. Feb 26 20:37:28 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:28 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:28 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:28 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:28 volumio go-librespot[18108]: Librespot-go daemon starting... Feb 26 20:37:28 volumio go-librespot[18108]: time="2025-02-26T20:37:28+03:00" level=info msg="generated new device id: d7d062695b604bfe758c3bf79c9cec57b361ac93" Feb 26 20:37:28 volumio go-librespot[18108]: time="2025-02-26T20:37:28+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:31 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:31 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154225. Feb 26 20:37:31 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:31 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:31 volumio go-librespot[18123]: Librespot-go daemon starting... Feb 26 20:37:31 volumio go-librespot[18123]: time="2025-02-26T20:37:31+03:00" level=info msg="generated new device id: d3c12367853981441c083dcd11c946fdc467156d" Feb 26 20:37:31 volumio go-librespot[18123]: time="2025-02-26T20:37:31+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:34 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:34 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:34 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:34 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:34 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:34 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:34 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:34 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:34 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:34 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:34 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:34 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:34 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:34 volumio volumio[854]: info: Listing playlists Feb 26 20:37:34 volumio volumio[854]: info: Listing playlists Feb 26 20:37:34 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:34 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154226. Feb 26 20:37:34 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:34 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:34 volumio go-librespot[18166]: Librespot-go daemon starting... Feb 26 20:37:34 volumio go-librespot[18166]: time="2025-02-26T20:37:34+03:00" level=info msg="generated new device id: 4b9305214118954de24de7b5ca767a7962ad8a4c" Feb 26 20:37:35 volumio go-librespot[18166]: time="2025-02-26T20:37:35+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:36 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:36 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:36 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:37 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:37 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154227. Feb 26 20:37:38 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:38 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:38 volumio go-librespot[18223]: Librespot-go daemon starting... Feb 26 20:37:38 volumio go-librespot[18223]: time="2025-02-26T20:37:38+03:00" level=info msg="generated new device id: 1193b13de0fd5afe1e4bc6afdb37d70145769b36" Feb 26 20:37:38 volumio go-librespot[18223]: time="2025-02-26T20:37:38+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:40 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:40 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154228. Feb 26 20:37:41 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:41 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:41 volumio go-librespot[18236]: Librespot-go daemon starting... Feb 26 20:37:41 volumio go-librespot[18236]: time="2025-02-26T20:37:41+03:00" level=info msg="generated new device id: 7ab463e518be82c78d903a48890a67a567d1336c" Feb 26 20:37:41 volumio go-librespot[18236]: time="2025-02-26T20:37:41+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:43 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:43 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:44 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:44 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:44 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:44 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:44 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:44 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:44 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:44 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:44 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:44 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:44 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:44 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:44 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:44 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:44 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:44 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:44 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:44 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:44 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154229. Feb 26 20:37:44 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:44 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:44 volumio go-librespot[18279]: Librespot-go daemon starting... Feb 26 20:37:44 volumio go-librespot[18279]: time="2025-02-26T20:37:44+03:00" level=info msg="generated new device id: 477f97700f902524bc7560c4cfc95322c14999da" Feb 26 20:37:45 volumio go-librespot[18279]: time="2025-02-26T20:37:45+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:46 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:46 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:46 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:47 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:47 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154230. Feb 26 20:37:48 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:48 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:48 volumio go-librespot[18292]: Librespot-go daemon starting... Feb 26 20:37:48 volumio go-librespot[18292]: time="2025-02-26T20:37:48+03:00" level=info msg="generated new device id: 8ff61c4fcde3dc3327029458f3fbdd7bd4d32f85" Feb 26 20:37:48 volumio go-librespot[18292]: time="2025-02-26T20:37:48+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:49 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:49 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154231. Feb 26 20:37:51 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:51 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:51 volumio go-librespot[18305]: Librespot-go daemon starting... Feb 26 20:37:51 volumio go-librespot[18305]: time="2025-02-26T20:37:51+03:00" level=info msg="generated new device id: 2a6dde4d0d708857b11802a3686fdc8f2c080ee1" Feb 26 20:37:51 volumio go-librespot[18305]: time="2025-02-26T20:37:51+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:52 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:52 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:54 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:37:54 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:37:54 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:37:54 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:37:54 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:37:54 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:37:54 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:54 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:37:54 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:37:54 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:54 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:37:54 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:54 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:54 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:37:54 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:37:54 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:37:54 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:37:54 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:37:54 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:37:54 volumio volumio[854]: info: Listing playlists Feb 26 20:37:54 volumio volumio[854]: info: Listing playlists Feb 26 20:37:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154232. Feb 26 20:37:54 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:54 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:54 volumio go-librespot[18346]: Librespot-go daemon starting... Feb 26 20:37:54 volumio go-librespot[18346]: time="2025-02-26T20:37:54+03:00" level=info msg="generated new device id: d53171901d45001b2ec6e677ed8f4050bab78dd7" Feb 26 20:37:54 volumio go-librespot[18346]: time="2025-02-26T20:37:54+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:55 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:55 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:37:57 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:37:57 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:37:57 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:37:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:37:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154233. Feb 26 20:37:57 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:37:57 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:37:57 volumio go-librespot[18361]: Librespot-go daemon starting... Feb 26 20:37:57 volumio go-librespot[18361]: time="2025-02-26T20:37:57+03:00" level=info msg="generated new device id: d5b62843d299a168dfdd88c555962d333c5fd76e" Feb 26 20:37:58 volumio go-librespot[18361]: time="2025-02-26T20:37:58+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:37:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:37:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:37:58 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:37:58 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154234. Feb 26 20:38:01 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:01 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:01 volumio go-librespot[18376]: Librespot-go daemon starting... Feb 26 20:38:01 volumio go-librespot[18376]: time="2025-02-26T20:38:01+03:00" level=info msg="generated new device id: 409d49e1c3a22060c3ba9cdbdf6d05c7910b29d2" Feb 26 20:38:01 volumio go-librespot[18376]: time="2025-02-26T20:38:01+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:01 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:01 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:04 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:38:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:38:04 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:38:04 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:38:04 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:38:04 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:38:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:38:04 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:38:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:04 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:38:04 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:04 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:38:04 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:04 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:38:04 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:38:04 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:04 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154235. Feb 26 20:38:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:04 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:04 volumio go-librespot[18407]: Librespot-go daemon starting... Feb 26 20:38:04 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:04 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:04 volumio go-librespot[18407]: time="2025-02-26T20:38:04+03:00" level=info msg="generated new device id: 5700150436e952e34001f88b758837803de0c70b" Feb 26 20:38:04 volumio go-librespot[18407]: time="2025-02-26T20:38:04+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:07 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:38:07 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:07 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:07 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:38:07 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:38:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154236. Feb 26 20:38:07 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:07 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:07 volumio go-librespot[18431]: Librespot-go daemon starting... Feb 26 20:38:07 volumio go-librespot[18431]: time="2025-02-26T20:38:07+03:00" level=info msg="generated new device id: d595948b60f4302ea08eb86924945d039b872745" Feb 26 20:38:08 volumio go-librespot[18431]: time="2025-02-26T20:38:08+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:10 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:10 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154237. Feb 26 20:38:11 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:11 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:11 volumio go-librespot[18446]: Librespot-go daemon starting... Feb 26 20:38:11 volumio go-librespot[18446]: time="2025-02-26T20:38:11+03:00" level=info msg="generated new device id: a0d187007d396077fb05c4d7ba9e84ee8c3e9e95" Feb 26 20:38:11 volumio go-librespot[18446]: time="2025-02-26T20:38:11+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:13 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:13 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:38:14 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:38:14 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:38:14 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:38:14 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:38:14 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:38:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:38:14 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:38:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:14 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:38:14 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:14 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:38:14 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:14 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:38:14 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:38:14 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:14 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:14 volumio volumio[854]: info: Listing playlists Feb 26 20:38:14 volumio volumio[854]: info: Listing playlists Feb 26 20:38:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154238. Feb 26 20:38:14 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:14 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:14 volumio go-librespot[18487]: Librespot-go daemon starting... Feb 26 20:38:14 volumio go-librespot[18487]: time="2025-02-26T20:38:14+03:00" level=info msg="generated new device id: 9b56b38058acd37efc582b1425a0cfa100ac5860" Feb 26 20:38:14 volumio go-librespot[18487]: time="2025-02-26T20:38:14+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:16 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:16 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:17 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:38:17 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:38:17 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:38:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154239. Feb 26 20:38:18 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:18 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:18 volumio go-librespot[18502]: Librespot-go daemon starting... Feb 26 20:38:18 volumio go-librespot[18502]: time="2025-02-26T20:38:18+03:00" level=info msg="generated new device id: 78dbc88484bee49e42d68e9510df60287ff4ba43" Feb 26 20:38:18 volumio go-librespot[18502]: time="2025-02-26T20:38:18+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:19 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:19 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154240. Feb 26 20:38:21 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:21 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:21 volumio go-librespot[18515]: Librespot-go daemon starting... Feb 26 20:38:21 volumio go-librespot[18515]: time="2025-02-26T20:38:21+03:00" level=info msg="generated new device id: 156d6886f6dd7c4bade46fe0b496c60986b04c25" Feb 26 20:38:21 volumio go-librespot[18515]: time="2025-02-26T20:38:21+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:22 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:22 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:24 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:38:24 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:38:24 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:38:24 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:38:24 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:38:24 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:38:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:38:24 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:38:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:24 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:38:24 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:24 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:38:24 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:24 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:38:24 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:38:24 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:24 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154241. Feb 26 20:38:24 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:24 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:24 volumio go-librespot[18557]: Librespot-go daemon starting... Feb 26 20:38:24 volumio go-librespot[18557]: time="2025-02-26T20:38:24+03:00" level=info msg="generated new device id: 5d409a56aec5b924a97c5105cf8a843b9994c18f" Feb 26 20:38:24 volumio go-librespot[18557]: time="2025-02-26T20:38:24+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:25 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:25 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:27 volumio volumio[854]: info: MyVolumio login type: Token Feb 26 20:38:27 volumio volumio[854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 26 20:38:27 volumio volumio[854]: info: Retrying Login Due to Network Error Feb 26 20:38:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154242. Feb 26 20:38:28 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:28 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:28 volumio go-librespot[18573]: Librespot-go daemon starting... Feb 26 20:38:28 volumio go-librespot[18573]: time="2025-02-26T20:38:28+03:00" level=info msg="generated new device id: fe98b8850966966541c7840915c219a5dd4de804" Feb 26 20:38:28 volumio go-librespot[18573]: time="2025-02-26T20:38:28+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:28 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:28 volumio volumio[854]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 26 20:38:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154243. Feb 26 20:38:31 volumio systemd[1]: Stopped go-librespot Daemon. Feb 26 20:38:31 volumio systemd[1]: Started go-librespot Daemon. Feb 26 20:38:31 volumio go-librespot[18586]: Librespot-go daemon starting... Feb 26 20:38:31 volumio go-librespot[18586]: time="2025-02-26T20:38:31+03:00" level=info msg="generated new device id: 26dea43c40d8d2ee945deadb6d613e7169452b8f" Feb 26 20:38:31 volumio volumio[854]: info: Initializing connection to go-librespot Websocket Feb 26 20:38:31 volumio go-librespot[18586]: time="2025-02-26T20:38:31+03:00" level=debug msg="new websocket client" Feb 26 20:38:31 volumio volumio[854]: info: Connection to go-librespot Websocket established Feb 26 20:38:31 volumio go-librespot[18586]: time="2025-02-26T20:38:31+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Feb 26 20:38:31 volumio volumio[854]: info: Connection to go-librespot Websocket closed Feb 26 20:38:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:38:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 20:38:34 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:38:34 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:38:34 volumio volumio[854]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 26 20:38:34 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:38:34 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:38:34 volumio volumio[854]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 26 20:38:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 26 20:38:34 volumio volumio[854]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 26 20:38:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:34 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 26 20:38:34 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:34 volumio volumio[854]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 26 20:38:34 volumio volumio[854]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 26 20:38:34 volumio volumio[854]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 26 20:38:34 volumio volumio[854]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 26 20:38:34 volumio volumio[854]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 26 20:38:34 volumio volumio[854]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 26 20:38:34 volumio volumio[854]: info: Listing playlists Feb 26 20:38:34 volumio volumio[854]: info: Listing playlists Feb 26 20:38:34 volumio volumio[854]: info: Getting Spotify volume Feb 26 20:38:34 volumio volumio[854]: (node:854) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 20:38:34 volumio volumio[854]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 26 20:38:34 volumio volumio[854]: (node:854) 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: 7261) Feb 26 20:38:34 volumio volumio[854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 26 20:38:34 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Feb 26 20:38:34 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Feb 26 20:38:34 volumio volumio[854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:38:34 volumio volumio[854]: TypeError: Cannot read property 'name' of undefined Feb 26 20:38:34 volumio volumio[854]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 26 20:38:34 volumio volumio[854]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 26 20:38:34 volumio volumio[854]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Feb 26 20:38:34 volumio volumio[854]: at Socket.emit (events.js:400:28) Feb 26 20:38:34 volumio volumio[854]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 26 20:38:34 volumio volumio[854]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 26 20:38:34 volumio volumio[854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:38:34 volumio sudo[18637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-26 20:37 Feb 26 20:38:34 volumio sudo[18637]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"