-- Logs begin at Mon 2025-12-22 02:49:01 +03, end at Tue 2025-12-23 02:06:51 +03. -- Dec 23 02:05:00 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:00 volumio-altkat go-librespot[19680]: time="2025-12-23T02:05:00+03:00" level=debug msg="new websocket client" Dec 23 02:05:00 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:05:03 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:05:03 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:05:03 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:05:04 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:05:04 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:04 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:09 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:09 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:10 volumio-altkat go-librespot[19680]: time="2025-12-23T02:05:10+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:05:10 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:05:10 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:05:10 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:05:10 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:05:10 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:05:10 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:05:10 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:05:10 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:05:10 volumio-altkat volumio[652]: (node:652) 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: 187) Dec 23 02:05:10 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:05:13 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:13 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:05:13 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:05:13 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 179. Dec 23 02:05:13 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:05:13 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:05:13 volumio-altkat go-librespot[19719]: go-librespot daemon starting... Dec 23 02:05:13 volumio-altkat go-librespot[19719]: time="2025-12-23T02:05:13+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:05:13 volumio-altkat go-librespot[19719]: time="2025-12-23T02:05:13+03:00" level=debug msg="app state loaded" Dec 23 02:05:13 volumio-altkat go-librespot[19719]: time="2025-12-23T02:05:13+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:05:14 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:14 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:16 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:16 volumio-altkat go-librespot[19719]: time="2025-12-23T02:05:16+03:00" level=debug msg="new websocket client" Dec 23 02:05:16 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:05:19 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:19 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:19 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:05:19 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:05:19 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:05:19 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:05:24 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:24 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:25 volumio-altkat go-librespot[19719]: time="2025-12-23T02:05:25+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:05:25 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:05:25 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:05:25 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:05:25 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:05:25 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:05:25 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:05:25 volumio-altkat volumio[652]: (node:652) 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: 188) Dec 23 02:05:25 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:05:25 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:05:25 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:05:28 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:28 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:05:28 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 180. Dec 23 02:05:28 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:05:28 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:05:28 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:05:28 volumio-altkat go-librespot[19741]: go-librespot daemon starting... Dec 23 02:05:28 volumio-altkat go-librespot[19741]: time="2025-12-23T02:05:28+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:05:28 volumio-altkat go-librespot[19741]: time="2025-12-23T02:05:28+03:00" level=debug msg="app state loaded" Dec 23 02:05:28 volumio-altkat go-librespot[19741]: time="2025-12-23T02:05:28+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:05:29 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:29 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:31 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:31 volumio-altkat go-librespot[19741]: time="2025-12-23T02:05:31+03:00" level=debug msg="new websocket client" Dec 23 02:05:31 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:05:34 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:34 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:34 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:05:34 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:05:34 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:05:34 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:05:39 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:39 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:40 volumio-altkat go-librespot[19741]: time="2025-12-23T02:05:40+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:05:40 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:05:40 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:05:40 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:05:40 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:05:40 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:05:40 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:05:40 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:05:40 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:05:40 volumio-altkat volumio[652]: (node:652) 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: 189) Dec 23 02:05:40 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:05:43 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:43 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:05:43 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:05:43 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 181. Dec 23 02:05:43 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:05:43 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:05:43 volumio-altkat go-librespot[19780]: go-librespot daemon starting... Dec 23 02:05:43 volumio-altkat go-librespot[19780]: time="2025-12-23T02:05:43+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:05:43 volumio-altkat go-librespot[19780]: time="2025-12-23T02:05:43+03:00" level=debug msg="app state loaded" Dec 23 02:05:43 volumio-altkat go-librespot[19780]: time="2025-12-23T02:05:43+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:05:44 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:44 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:46 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:46 volumio-altkat go-librespot[19780]: time="2025-12-23T02:05:46+03:00" level=debug msg="new websocket client" Dec 23 02:05:46 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:05:49 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:49 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:49 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:05:49 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:05:49 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:05:49 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:05:54 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:54 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:05:55 volumio-altkat go-librespot[19780]: time="2025-12-23T02:05:55+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:05:55 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:05:55 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:05:55 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:05:55 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:05:55 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:05:55 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:05:55 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:05:55 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:05:55 volumio-altkat volumio[652]: (node:652) 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: 190) Dec 23 02:05:55 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:05:58 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:05:58 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:05:59 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:05:59 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 182. Dec 23 02:05:59 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:05:59 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:05:59 volumio-altkat go-librespot[19835]: go-librespot daemon starting... Dec 23 02:05:59 volumio-altkat go-librespot[19835]: time="2025-12-23T02:05:59+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:05:59 volumio-altkat go-librespot[19835]: time="2025-12-23T02:05:59+03:00" level=debug msg="app state loaded" Dec 23 02:05:59 volumio-altkat go-librespot[19835]: time="2025-12-23T02:05:59+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:05:59 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:05:59 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:01 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:01 volumio-altkat go-librespot[19835]: time="2025-12-23T02:06:01+03:00" level=debug msg="new websocket client" Dec 23 02:06:01 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:06:04 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:04 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:04 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:06:04 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:06:04 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:06:04 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:06:09 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:09 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:11 volumio-altkat go-librespot[19835]: time="2025-12-23T02:06:11+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:06:11 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:06:11 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:06:11 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:06:11 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:06:11 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:06:11 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:06:11 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:06:11 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:06:11 volumio-altkat volumio[652]: (node:652) 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: 191) Dec 23 02:06:11 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:06:14 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:14 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:06:14 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:14 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:14 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:06:14 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 183. Dec 23 02:06:14 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:06:14 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:06:14 volumio-altkat go-librespot[19874]: go-librespot daemon starting... Dec 23 02:06:14 volumio-altkat go-librespot[19874]: time="2025-12-23T02:06:14+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:06:14 volumio-altkat go-librespot[19874]: time="2025-12-23T02:06:14+03:00" level=debug msg="app state loaded" Dec 23 02:06:14 volumio-altkat go-librespot[19874]: time="2025-12-23T02:06:14+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:06:17 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:17 volumio-altkat go-librespot[19874]: time="2025-12-23T02:06:17+03:00" level=debug msg="new websocket client" Dec 23 02:06:17 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:06:19 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:19 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:20 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:06:20 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:06:20 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:06:20 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:06:24 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:24 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:26 volumio-altkat go-librespot[19874]: time="2025-12-23T02:06:26+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:06:26 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:06:26 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:06:26 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:06:26 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:06:26 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:06:26 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:06:26 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:06:26 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:06:26 volumio-altkat volumio[652]: (node:652) 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: 192) Dec 23 02:06:26 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:06:29 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:29 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:29 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:29 volumio-altkat volumio[652]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 02:06:29 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:06:29 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 184. Dec 23 02:06:29 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:06:29 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:06:29 volumio-altkat go-librespot[19899]: go-librespot daemon starting... Dec 23 02:06:29 volumio-altkat go-librespot[19899]: time="2025-12-23T02:06:29+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:06:29 volumio-altkat go-librespot[19899]: time="2025-12-23T02:06:29+03:00" level=debug msg="app state loaded" Dec 23 02:06:29 volumio-altkat go-librespot[19899]: time="2025-12-23T02:06:29+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:06:32 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:32 volumio-altkat go-librespot[19899]: time="2025-12-23T02:06:32+03:00" level=debug msg="new websocket client" Dec 23 02:06:32 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:06:34 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:34 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:35 volumio-altkat volumio[652]: info: Getting Spotify volume Dec 23 02:06:35 volumio-altkat volumio[652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 02:06:35 volumio-altkat volumio[652]: info: CoreCommandRouter::volumioGetState Dec 23 02:06:35 volumio-altkat volumio[652]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 23 02:06:39 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:39 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:41 volumio-altkat go-librespot[19899]: time="2025-12-23T02:06:41+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 23 02:06:41 volumio-altkat volumio[652]: (node:652) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 23 02:06:41 volumio-altkat volumio[652]: at connResetException (internal/errors.js:607:14) Dec 23 02:06:41 volumio-altkat volumio[652]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 23 02:06:41 volumio-altkat volumio[652]: at Socket.emit (events.js:327:22) Dec 23 02:06:41 volumio-altkat volumio[652]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 23 02:06:41 volumio-altkat volumio[652]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 23 02:06:41 volumio-altkat volumio[652]: (node:652) 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: 193) Dec 23 02:06:41 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket closed Dec 23 02:06:41 volumio-altkat systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 02:06:41 volumio-altkat systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 02:06:44 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:44 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:44 volumio-altkat volumio[652]: info: Initializing connection to go-librespot Websocket Dec 23 02:06:44 volumio-altkat systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 23 02:06:44 volumio-altkat systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 185. Dec 23 02:06:44 volumio-altkat systemd[1]: Stopped go-librespot Daemon. Dec 23 02:06:44 volumio-altkat systemd[1]: Started go-librespot Daemon. Dec 23 02:06:44 volumio-altkat go-librespot[19935]: go-librespot daemon starting... Dec 23 02:06:44 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:44+03:00" level=info msg="running go-librespot 0.4.0" Dec 23 02:06:44 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:44+03:00" level=debug msg="app state loaded" Dec 23 02:06:44 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:44+03:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 02:06:48 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:48+03:00" level=debug msg="new websocket client" Dec 23 02:06:48 volumio-altkat volumio[652]: info: Connection to go-librespot Websocket established Dec 23 02:06:49 volumio-altkat volumio[652]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Dec 23 02:06:49 volumio-altkat volumio[652]: info: Discovery: Started advertising with name: Volumio-AltKat Dec 23 02:06:50 volumio-altkat volumio[652]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 02:06:50 volumio-altkat volumio[652]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Dec 23 02:06:50 volumio-altkat volumio[652]: code: 'auth/network-request-failed', Dec 23 02:06:50 volumio-altkat volumio[652]: a: null Dec 23 02:06:50 volumio-altkat volumio[652]: } Dec 23 02:06:50 volumio-altkat volumio[652]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 02:06:51 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:51+03:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 23 02:06:51 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:51+03:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 23 02:06:51 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:51+03:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 23 02:06:51 volumio-altkat sudo[19986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 02:05 Dec 23 02:06:51 volumio-altkat sudo[19986]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 02:06:51 volumio-altkat go-librespot[19935]: time="2025-12-23T02:06:51+03:00" level=info msg="zeroconf server listening on port 37797" PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"