-- Logs begin at Sat 2025-03-01 06:26:50 MSK, end at Sat 2025-03-01 08:46:21 MSK. -- Mar 01 08:45:00 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:00 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:00 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:00 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:00 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:01 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219519. Mar 01 08:45:01 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:01 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:01 volumio go-librespot[10070]: Librespot-go daemon starting... Mar 01 08:45:01 volumio go-librespot[10070]: time="2025-03-01T08:45:01+03:00" level=info msg="generated new device id: 8756c0982b81690fd9fb1e0337cc464f8b7b499c" Mar 01 08:45:01 volumio go-librespot[10070]: time="2025-03-01T08:45:01+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:03 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:03 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219520. Mar 01 08:45:04 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:04 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:04 volumio go-librespot[10103]: Librespot-go daemon starting... Mar 01 08:45:04 volumio go-librespot[10103]: time="2025-03-01T08:45:04+03:00" level=info msg="generated new device id: 80a2dca53d94d97753ba9324ad658a5610c90a0e" Mar 01 08:45:05 volumio go-librespot[10103]: time="2025-03-01T08:45:05+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:06 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:06 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219521. Mar 01 08:45:08 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:08 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:08 volumio go-librespot[10113]: Librespot-go daemon starting... Mar 01 08:45:08 volumio go-librespot[10113]: time="2025-03-01T08:45:08+03:00" level=info msg="generated new device id: d20d56f7f481a3adf7fb6e7809928ce92838e106" Mar 01 08:45:08 volumio go-librespot[10113]: time="2025-03-01T08:45:08+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:09 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:09 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:10 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:10 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:10 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219522. Mar 01 08:45:11 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:11 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:11 volumio go-librespot[10120]: Librespot-go daemon starting... Mar 01 08:45:11 volumio go-librespot[10120]: time="2025-03-01T08:45:11+03:00" level=info msg="generated new device id: 1d595954586982f28baf5f8e5b9127e700c41de5" Mar 01 08:45:11 volumio go-librespot[10120]: time="2025-03-01T08:45:11+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:11 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:11 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:11 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:12 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:12 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219523. Mar 01 08:45:14 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:14 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:14 volumio go-librespot[10153]: Librespot-go daemon starting... Mar 01 08:45:14 volumio go-librespot[10153]: time="2025-03-01T08:45:14+03:00" level=info msg="generated new device id: fb3a003f8c665cc843bfe7c2cf7162b3bb74db60" Mar 01 08:45:14 volumio go-librespot[10153]: time="2025-03-01T08:45:14+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:15 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:15 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219524. Mar 01 08:45:17 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:17 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:17 volumio go-librespot[10163]: Librespot-go daemon starting... Mar 01 08:45:17 volumio go-librespot[10163]: time="2025-03-01T08:45:17+03:00" level=info msg="generated new device id: c41faaa9a35a3da1eb2ea5aaa2431c7f127e9bc8" Mar 01 08:45:18 volumio go-librespot[10163]: time="2025-03-01T08:45:18+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:18 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:18 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:20 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:20 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:20 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219525. Mar 01 08:45:21 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:21 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:21 volumio go-librespot[10170]: Librespot-go daemon starting... Mar 01 08:45:21 volumio go-librespot[10170]: time="2025-03-01T08:45:21+03:00" level=info msg="generated new device id: 4be6a3d9efd40c6b541d8d545195e85e60e92456" Mar 01 08:45:21 volumio go-librespot[10170]: time="2025-03-01T08:45:21+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:21 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:21 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:21 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219526. Mar 01 08:45:24 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:24 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:24 volumio go-librespot[10201]: Librespot-go daemon starting... Mar 01 08:45:24 volumio go-librespot[10201]: time="2025-03-01T08:45:24+03:00" level=info msg="generated new device id: 8ba3b1036f795898f2c2056a6744fb7feacb3e03" Mar 01 08:45:24 volumio go-librespot[10201]: time="2025-03-01T08:45:24+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:24 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:24 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219527. Mar 01 08:45:27 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:27 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:27 volumio go-librespot[10211]: Librespot-go daemon starting... Mar 01 08:45:27 volumio go-librespot[10211]: time="2025-03-01T08:45:27+03:00" level=info msg="generated new device id: c36b92f4cf69909d9c533a13827f5803d4784e49" Mar 01 08:45:27 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:27 volumio go-librespot[10211]: time="2025-03-01T08:45:27+03:00" level=debug msg="new websocket client" Mar 01 08:45:27 volumio volumio[18658]: info: Connection to go-librespot Websocket established Mar 01 08:45:27 volumio go-librespot[10211]: time="2025-03-01T08:45:27+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:27 volumio volumio[18658]: info: Connection to go-librespot Websocket closed Mar 01 08:45:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:30 volumio volumio[18658]: info: Getting Spotify volume Mar 01 08:45:30 volumio volumio[18658]: (node:18658) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:30 volumio volumio[18658]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 01 08:45:30 volumio volumio[18658]: (node:18658) 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: 3069) Mar 01 08:45:30 volumio volumio[18658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 01 08:45:30 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:30 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:30 volumio volumio[18658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 01 08:45:30 volumio volumio[18658]: SPOTIFY: SPOTIFY VOLUME 100 Mar 01 08:45:30 volumio volumio[18658]: SPOTIFY: VOLUMIO VOLUME 100 Mar 01 08:45:30 volumio volumio[18658]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 01 08:45:30 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:30 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:30 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:30 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219528. Mar 01 08:45:30 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:30 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:30 volumio go-librespot[10219]: Librespot-go daemon starting... Mar 01 08:45:30 volumio go-librespot[10219]: time="2025-03-01T08:45:30+03:00" level=info msg="generated new device id: 8ba8867f727072fcae937642077f3f268e0e00b0" Mar 01 08:45:31 volumio go-librespot[10219]: time="2025-03-01T08:45:31+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:31 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:31 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:31 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:33 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:33 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219529. Mar 01 08:45:34 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:34 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:34 volumio go-librespot[10251]: Librespot-go daemon starting... Mar 01 08:45:34 volumio go-librespot[10251]: time="2025-03-01T08:45:34+03:00" level=info msg="generated new device id: 02ba7e4047c5231a208449a8c2474f78ae3abea7" Mar 01 08:45:34 volumio go-librespot[10251]: time="2025-03-01T08:45:34+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:36 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:36 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219530. Mar 01 08:45:37 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:37 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:37 volumio go-librespot[10261]: Librespot-go daemon starting... Mar 01 08:45:37 volumio go-librespot[10261]: time="2025-03-01T08:45:37+03:00" level=info msg="generated new device id: 161e4357e1ac34aebb68fa3b3844d64e78b8dd7b" Mar 01 08:45:37 volumio go-librespot[10261]: time="2025-03-01T08:45:37+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:39 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:39 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219531. Mar 01 08:45:40 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:40 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:40 volumio go-librespot[10268]: Librespot-go daemon starting... Mar 01 08:45:40 volumio go-librespot[10268]: time="2025-03-01T08:45:40+03:00" level=info msg="generated new device id: a6b2c3e022a5b618276f523631719da70a68845e" Mar 01 08:45:40 volumio go-librespot[10268]: time="2025-03-01T08:45:40+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:40 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:41 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:41 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:41 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:42 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:42 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219532. Mar 01 08:45:43 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:43 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:43 volumio go-librespot[10301]: Librespot-go daemon starting... Mar 01 08:45:43 volumio go-librespot[10301]: time="2025-03-01T08:45:43+03:00" level=info msg="generated new device id: 2100e4b0e66cbd327e4c34c9f8753183d6606852" Mar 01 08:45:44 volumio go-librespot[10301]: time="2025-03-01T08:45:44+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:45 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:45 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219533. Mar 01 08:45:47 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:47 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:47 volumio go-librespot[10311]: Librespot-go daemon starting... Mar 01 08:45:47 volumio go-librespot[10311]: time="2025-03-01T08:45:47+03:00" level=info msg="generated new device id: 079b3d6fff8c43d4eeeb4dd86b9a5a28687f76e2" Mar 01 08:45:47 volumio go-librespot[10311]: time="2025-03-01T08:45:47+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:48 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:48 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219534. Mar 01 08:45:50 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:50 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:50 volumio go-librespot[10363]: Librespot-go daemon starting... Mar 01 08:45:50 volumio go-librespot[10363]: time="2025-03-01T08:45:50+03:00" level=info msg="generated new device id: 1bd4fe67d4435e77a40286d13860b4e813f64b16" Mar 01 08:45:50 volumio go-librespot[10363]: time="2025-03-01T08:45:50+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:51 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:45:51 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:45:51 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:45:51 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:45:51 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:51 volumio volumio[18658]: info: Listing playlists Mar 01 08:45:51 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:51 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219535. Mar 01 08:45:53 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:53 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:53 volumio go-librespot[10396]: Librespot-go daemon starting... Mar 01 08:45:53 volumio go-librespot[10396]: time="2025-03-01T08:45:53+03:00" level=info msg="generated new device id: 686f4a0a8ce077ddce429d97f055b72b0f9fd37c" Mar 01 08:45:54 volumio go-librespot[10396]: time="2025-03-01T08:45:54+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:54 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:54 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:45:56 volumio volumio[18658]: info: Mar 01 08:45:56 volumio volumio[18658]: ---------------------------- Client requests Volumio play Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::volumioPlay Mar 01 08:45:56 volumio volumio[18658]: verbose: UNSET VOLATILE: Service: squeezelite_mc Mar 01 08:45:56 volumio volumio[18658]: info: [squeezelite_mc] Pushing empty state... Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::servicePushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreStateMachine::pushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::volumioPushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::servicePushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreStateMachine::pushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreCommandRouter::volumioPushState Mar 01 08:45:56 volumio volumio[18658]: info: CoreStateMachine::play index undefined Mar 01 08:45:56 volumio volumio[18658]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 08:45:56 volumio volumio[18658]: info: CorePlayQueue::getTrack 0 Mar 01 08:45:56 volumio volumio[18658]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 01 08:45:56 volumio volumio[18658]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 01 08:45:56 volumio sudo[10407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 01 08:45:56 volumio sudo[10407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 08:45:56 volumio sudo[10407]: pam_unix(sudo:session): session closed for user root Mar 01 08:45:56 volumio volumio[18658]: info: PeppySpectrum ---peppyspectrum status pause Mar 01 08:45:56 volumio sudo[10410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 01 08:45:56 volumio sudo[10410]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 08:45:56 volumio sudo[10410]: pam_unix(sudo:session): session closed for user root Mar 01 08:45:56 volumio volumio[18658]: info: PeppySpectrum ---peppyspectrum status pause Mar 01 08:45:56 volumio sudo[10413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: SPOTIFY VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: VOLUMIO VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: SPOTIFY VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: VOLUMIO VOLUME 100 Mar 01 08:45:56 volumio volumio[18658]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 01 08:45:56 volumio sudo[10413]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 08:45:56 volumio sudo[10413]: pam_unix(sudo:session): session closed for user root Mar 01 08:45:56 volumio sudo[10415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 01 08:45:56 volumio sudo[10415]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 08:45:56 volumio volumio[18658]: info: peppymeterbasic Daemon Stop Mar 01 08:45:56 volumio sudo[10415]: pam_unix(sudo:session): session closed for user root Mar 01 08:45:56 volumio volumio[18658]: info: peppymeterbasic Daemon Stop Mar 01 08:45:56 volumio volumio[18658]: info: peppyspectrum Daemon Stop Mar 01 08:45:56 volumio volumio[18658]: info: peppyspectrum Daemon Stop Mar 01 08:45:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:45:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219536. Mar 01 08:45:57 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:45:57 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:45:57 volumio go-librespot[10425]: Librespot-go daemon starting... Mar 01 08:45:57 volumio go-librespot[10425]: time="2025-03-01T08:45:57+03:00" level=info msg="generated new device id: 044c0841036dc7c7826626618731ba816fe7dd64" Mar 01 08:45:57 volumio go-librespot[10425]: time="2025-03-01T08:45:57+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:45:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:45:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:45:57 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:45:57 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219537. Mar 01 08:46:00 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:00 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:00 volumio go-librespot[10438]: Librespot-go daemon starting... Mar 01 08:46:00 volumio go-librespot[10438]: time="2025-03-01T08:46:00+03:00" level=info msg="generated new device id: 47ea98bd12dda28c5c78385d2c918fceac325b24" Mar 01 08:46:00 volumio go-librespot[10438]: time="2025-03-01T08:46:00+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:00 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:00 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:01 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:46:01 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:46:01 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:46:01 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:46:01 volumio volumio[18658]: info: CorePlayQueue::getTrack 0 Mar 01 08:46:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219538. Mar 01 08:46:03 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:03 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:03 volumio go-librespot[10470]: Librespot-go daemon starting... Mar 01 08:46:03 volumio go-librespot[10470]: time="2025-03-01T08:46:03+03:00" level=info msg="generated new device id: 5f30ccc9d07e6df81306f94c47b851d2a7415675" Mar 01 08:46:03 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:03 volumio go-librespot[10470]: time="2025-03-01T08:46:03+03:00" level=debug msg="new websocket client" Mar 01 08:46:03 volumio volumio[18658]: info: Connection to go-librespot Websocket established Mar 01 08:46:03 volumio go-librespot[10470]: time="2025-03-01T08:46:03+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:03 volumio volumio[18658]: info: Connection to go-librespot Websocket closed Mar 01 08:46:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:06 volumio volumio[18658]: info: Getting Spotify volume Mar 01 08:46:06 volumio volumio[18658]: (node:18658) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:06 volumio volumio[18658]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 01 08:46:06 volumio volumio[18658]: (node:18658) 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: 3070) Mar 01 08:46:06 volumio volumio[18658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 01 08:46:06 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:06 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:06 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:46:06 volumio volumio[18658]: info: CorePlayQueue::getTrack 0 Mar 01 08:46:06 volumio volumio[18658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 01 08:46:06 volumio volumio[18658]: SPOTIFY: SPOTIFY VOLUME 100 Mar 01 08:46:06 volumio volumio[18658]: SPOTIFY: VOLUMIO VOLUME 100 Mar 01 08:46:06 volumio volumio[18658]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 01 08:46:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219539. Mar 01 08:46:06 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:06 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:06 volumio go-librespot[10480]: Librespot-go daemon starting... Mar 01 08:46:06 volumio go-librespot[10480]: time="2025-03-01T08:46:06+03:00" level=info msg="generated new device id: 5fa402af094fb4af402ec38b426e3886e34fd458" Mar 01 08:46:07 volumio go-librespot[10480]: time="2025-03-01T08:46:07+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:09 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:09 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219540. Mar 01 08:46:10 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:10 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:10 volumio go-librespot[10487]: Librespot-go daemon starting... Mar 01 08:46:10 volumio go-librespot[10487]: time="2025-03-01T08:46:10+03:00" level=info msg="generated new device id: 9516745917438d0dc1fda9c35889bef1d5049598" Mar 01 08:46:10 volumio go-librespot[10487]: time="2025-03-01T08:46:10+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:11 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:46:11 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:46:11 volumio volumio[18658]: info: CorePlayQueue::getTrack 0 Mar 01 08:46:11 volumio volumio[18658]: info: Listing playlists Mar 01 08:46:11 volumio volumio[18658]: info: Listing playlists Mar 01 08:46:11 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:46:11 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:46:12 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:12 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219541. Mar 01 08:46:13 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:13 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:13 volumio go-librespot[10519]: Librespot-go daemon starting... Mar 01 08:46:13 volumio go-librespot[10519]: time="2025-03-01T08:46:13+03:00" level=info msg="generated new device id: 253485fab84a5684a8c26f8354cca69cc515bbdc" Mar 01 08:46:13 volumio go-librespot[10519]: time="2025-03-01T08:46:13+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:14 volumio volumio[18658]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 08:46:14 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: error: error Mar 01 08:46:15 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:15 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:16 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 08:46:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219542. Mar 01 08:46:16 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:16 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:16 volumio go-librespot[10530]: Librespot-go daemon starting... Mar 01 08:46:16 volumio go-librespot[10530]: time="2025-03-01T08:46:16+03:00" level=info msg="generated new device id: bead07d799fec3247613bf5f92e22e7fc4246dba" Mar 01 08:46:17 volumio go-librespot[10530]: time="2025-03-01T08:46:17+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:18 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 08:46:18 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:18 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:19 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 08:46:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 01 08:46:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219543. Mar 01 08:46:20 volumio systemd[1]: Stopped go-librespot Daemon. Mar 01 08:46:20 volumio systemd[1]: Started go-librespot Daemon. Mar 01 08:46:20 volumio go-librespot[10537]: Librespot-go daemon starting... Mar 01 08:46:20 volumio go-librespot[10537]: time="2025-03-01T08:46:20+03:00" level=info msg="generated new device id: 66ee345768a646c47dca11af6ad8a9978f34a417" Mar 01 08:46:20 volumio go-librespot[10537]: time="2025-03-01T08:46:20+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: invalid status code from apresolve: 403" Mar 01 08:46:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 01 08:46:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 01 08:46:21 volumio volumio[18658]: info: CoreCommandRouter::volumioGetState Mar 01 08:46:21 volumio volumio[18658]: info: CorePlayQueue::getTrack 0 Mar 01 08:46:21 volumio volumio[18658]: info: MyVolumio login type: Token Mar 01 08:46:21 volumio volumio[18658]: info: Initializing connection to go-librespot Websocket Mar 01 08:46:21 volumio volumio[18658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 01 08:46:21 volumio volumio[18658]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 01 08:46:21 volumio volumio[18658]: info: Retrying Login Due to Network Error Mar 01 08:46:21 volumio volumio[18658]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 08:46:21 volumio volumio[18658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 08:46:21 volumio volumio[18658]: TypeError: Cannot read property 'length' of undefined Mar 01 08:46:21 volumio volumio[18658]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 01 08:46:21 volumio volumio[18658]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 01 08:46:21 volumio volumio[18658]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 01 08:46:21 volumio volumio[18658]: at Parser.emit (events.js:400:28) Mar 01 08:46:21 volumio volumio[18658]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 01 08:46:21 volumio volumio[18658]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 01 08:46:21 volumio volumio[18658]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 01 08:46:21 volumio volumio[18658]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 01 08:46:21 volumio volumio[18658]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 01 08:46:21 volumio volumio[18658]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 01 08:46:21 volumio volumio[18658]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 01 08:46:21 volumio volumio[18658]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 01 08:46:21 volumio volumio[18658]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 01 08:46:21 volumio volumio[18658]: at IncomingMessage.emit (events.js:412:35) Mar 01 08:46:21 volumio volumio[18658]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 01 08:46:21 volumio volumio[18658]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 01 08:46:21 volumio volumio[18658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 08:46:21 volumio sudo[10578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 08:45 Mar 01 08:46:21 volumio sudo[10578]: 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"