-- Logs begin at Mon 2025-03-03 21:43:03 CET, end at Tue 2025-03-04 02:24:32 CET. -- Mar 04 02:23:00 keuken2 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Mar 04 02:23:00 keuken2 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Mar 04 02:23:00 keuken2 systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 04 02:23:00 keuken2 systemd[1]: Started MyVolumio SSH Tunnel. Mar 04 02:23:00 keuken2 autossh[19921]: port set to 0, monitoring disabled Mar 04 02:23:00 keuken2 autossh[19921]: starting ssh (count 1) Mar 04 02:23:00 keuken2 autossh[19921]: ssh child pid is 19924 Mar 04 02:23:04 keuken2 go-librespot[19843]: time="2025-03-04T02:23:04+01: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" Mar 04 02:23:04 keuken2 volumio[10762]: (node:10762) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 04 02:23:04 keuken2 volumio[10762]: at connResetException (internal/errors.js:607:14) Mar 04 02:23:04 keuken2 volumio[10762]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 04 02:23:04 keuken2 volumio[10762]: at Socket.emit (events.js:327:22) Mar 04 02:23:04 keuken2 volumio[10762]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 04 02:23:04 keuken2 volumio[10762]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 04 02:23:04 keuken2 volumio[10762]: (node:10762) 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: 29555) Mar 04 02:23:04 keuken2 volumio[10762]: info: Connection to go-librespot Websocket closed Mar 04 02:23:04 keuken2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:23:04 keuken2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 02:23:07 keuken2 volumio[10762]: info: Initializing connection to go-librespot Websocket Mar 04 02:23:07 keuken2 volumio[10762]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 02:23:07 keuken2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 04 02:23:07 keuken2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49535. Mar 04 02:23:07 keuken2 systemd[1]: Stopped go-librespot Daemon. Mar 04 02:23:07 keuken2 systemd[1]: Started go-librespot Daemon. Mar 04 02:23:07 keuken2 go-librespot[19943]: go-librespot daemon starting... Mar 04 02:23:07 keuken2 go-librespot[19943]: time="2025-03-04T02:23:07+01:00" level=info msg="running go-librespot 0.2.0" Mar 04 02:23:07 keuken2 go-librespot[19943]: time="2025-03-04T02:23:07+01:00" level=debug msg="app state loaded" Mar 04 02:23:07 keuken2 go-librespot[19943]: time="2025-03-04T02:23:07+01:00" level=debug msg="stored credentials not found" Mar 04 02:23:07 keuken2 go-librespot[19943]: time="2025-03-04T02:23:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 02:23:10 keuken2 volumio[10762]: info: Initializing connection to go-librespot Websocket Mar 04 02:23:10 keuken2 go-librespot[19943]: time="2025-03-04T02:23:10+01:00" level=debug msg="new websocket client" Mar 04 02:23:10 keuken2 volumio[10762]: info: Connection to go-librespot Websocket established Mar 04 02:23:13 keuken2 volumio[10762]: info: Getting Spotify volume Mar 04 02:23:13 keuken2 volumio[10762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 04 02:23:13 keuken2 volumio[10762]: info: CoreCommandRouter::volumioGetState Mar 04 02:23:13 keuken2 volumio[10762]: info: CorePlayQueue::getTrack 0 Mar 04 02:23:13 keuken2 volumio[10762]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Mar 04 02:23:28 keuken2 volumiossh-tunnel[19920]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Mar 04 02:23:28 keuken2 autossh[19921]: ssh exited prematurely with status 255; autossh exiting Mar 04 02:23:28 keuken2 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:23:28 keuken2 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Mar 04 02:23:28 keuken2 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Mar 04 02:23:28 keuken2 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Mar 04 02:23:28 keuken2 systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 04 02:23:28 keuken2 systemd[1]: Started MyVolumio SSH Tunnel. Mar 04 02:23:28 keuken2 autossh[19983]: port set to 0, monitoring disabled Mar 04 02:23:28 keuken2 autossh[19983]: starting ssh (count 1) Mar 04 02:23:28 keuken2 autossh[19983]: ssh child pid is 19986 Mar 04 02:23:35 keuken2 go-librespot[19943]: time="2025-03-04T02:23:35+01: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" Mar 04 02:23:35 keuken2 volumio[10762]: (node:10762) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 04 02:23:35 keuken2 volumio[10762]: at connResetException (internal/errors.js:607:14) Mar 04 02:23:35 keuken2 volumio[10762]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 04 02:23:35 keuken2 volumio[10762]: at Socket.emit (events.js:327:22) Mar 04 02:23:35 keuken2 volumio[10762]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 04 02:23:35 keuken2 volumio[10762]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 04 02:23:35 keuken2 volumio[10762]: (node:10762) 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: 29556) Mar 04 02:23:35 keuken2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:23:35 keuken2 volumio[10762]: info: Connection to go-librespot Websocket closed Mar 04 02:23:35 keuken2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 02:23:38 keuken2 volumio[10762]: info: Initializing connection to go-librespot Websocket Mar 04 02:23:38 keuken2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 04 02:23:38 keuken2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49536. Mar 04 02:23:38 keuken2 systemd[1]: Stopped go-librespot Daemon. Mar 04 02:23:38 keuken2 systemd[1]: Started go-librespot Daemon. Mar 04 02:23:38 keuken2 go-librespot[20004]: go-librespot daemon starting... Mar 04 02:23:38 keuken2 go-librespot[20004]: time="2025-03-04T02:23:38+01:00" level=info msg="running go-librespot 0.2.0" Mar 04 02:23:38 keuken2 go-librespot[20004]: time="2025-03-04T02:23:38+01:00" level=debug msg="app state loaded" Mar 04 02:23:38 keuken2 go-librespot[20004]: time="2025-03-04T02:23:38+01:00" level=debug msg="stored credentials not found" Mar 04 02:23:38 keuken2 go-librespot[20004]: time="2025-03-04T02:23:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 02:23:56 keuken2 volumiossh-tunnel[19982]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Mar 04 02:23:56 keuken2 autossh[19983]: ssh exited prematurely with status 255; autossh exiting Mar 04 02:23:56 keuken2 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:23:56 keuken2 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Mar 04 02:23:57 keuken2 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Mar 04 02:23:57 keuken2 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Mar 04 02:23:57 keuken2 systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 04 02:23:57 keuken2 systemd[1]: Started MyVolumio SSH Tunnel. Mar 04 02:23:57 keuken2 autossh[20083]: port set to 0, monitoring disabled Mar 04 02:23:57 keuken2 autossh[20083]: starting ssh (count 1) Mar 04 02:23:57 keuken2 autossh[20083]: ssh child pid is 20086 Mar 04 02:23:59 keuken2 volumio[10762]: info: Connection to go-librespot Websocket established Mar 04 02:23:59 keuken2 go-librespot[20004]: time="2025-03-04T02:23:59+01:00" level=debug msg="new websocket client" Mar 04 02:24:02 keuken2 volumio[10762]: info: Getting Spotify volume Mar 04 02:24:06 keuken2 go-librespot[20004]: time="2025-03-04T02:24:06+01: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" Mar 04 02:24:06 keuken2 volumio[10762]: (node:10762) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 04 02:24:06 keuken2 volumio[10762]: at connResetException (internal/errors.js:607:14) Mar 04 02:24:06 keuken2 volumio[10762]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 04 02:24:06 keuken2 volumio[10762]: at Socket.emit (events.js:327:22) Mar 04 02:24:06 keuken2 volumio[10762]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 04 02:24:06 keuken2 volumio[10762]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 04 02:24:06 keuken2 volumio[10762]: (node:10762) 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: 29557) Mar 04 02:24:06 keuken2 volumio[10762]: info: Connection to go-librespot Websocket closed Mar 04 02:24:06 keuken2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:24:06 keuken2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 02:24:09 keuken2 volumio[10762]: info: Initializing connection to go-librespot Websocket Mar 04 02:24:09 keuken2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 04 02:24:09 keuken2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49537. Mar 04 02:24:09 keuken2 systemd[1]: Stopped go-librespot Daemon. Mar 04 02:24:09 keuken2 systemd[1]: Started go-librespot Daemon. Mar 04 02:24:09 keuken2 go-librespot[20104]: go-librespot daemon starting... Mar 04 02:24:10 keuken2 go-librespot[20104]: time="2025-03-04T02:24:10+01:00" level=info msg="running go-librespot 0.2.0" Mar 04 02:24:10 keuken2 go-librespot[20104]: time="2025-03-04T02:24:10+01:00" level=debug msg="app state loaded" Mar 04 02:24:10 keuken2 go-librespot[20104]: time="2025-03-04T02:24:10+01:00" level=debug msg="stored credentials not found" Mar 04 02:24:10 keuken2 go-librespot[20104]: time="2025-03-04T02:24:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 02:24:15 keuken2 volumio[10762]: verbose: New Socket.io Connection to 192.168.178.53:3000 from 192.168.178.34 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 04 02:24:19 keuken2 volumio[10762]: verbose: New Socket.io Connection to 192.168.178.53:3000 from 192.168.178.34 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 04 02:24:22 keuken2 volumio[10762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 04 02:24:22 keuken2 go-librespot[20104]: time="2025-03-04T02:24:22+01:00" level=debug msg="new websocket client" Mar 04 02:24:22 keuken2 volumio[10762]: info: Connection to go-librespot Websocket established Mar 04 02:24:25 keuken2 volumiossh-tunnel[20082]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Mar 04 02:24:25 keuken2 autossh[20083]: ssh exited prematurely with status 255; autossh exiting Mar 04 02:24:25 keuken2 systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Mar 04 02:24:25 keuken2 systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Mar 04 02:24:25 keuken2 systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Mar 04 02:24:25 keuken2 systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Mar 04 02:24:25 keuken2 systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 04 02:24:25 keuken2 systemd[1]: Started MyVolumio SSH Tunnel. Mar 04 02:24:25 keuken2 autossh[20144]: port set to 0, monitoring disabled Mar 04 02:24:25 keuken2 autossh[20144]: starting ssh (count 1) Mar 04 02:24:25 keuken2 autossh[20144]: ssh child pid is 20147 Mar 04 02:24:25 keuken2 volumio[10762]: info: Getting Spotify volume Mar 04 02:24:27 keuken2 volumio[10762]: info: Discovery: Connected to remote: 192.168.178.34 Mar 04 02:24:27 keuken2 volumio[10762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 04 02:24:27 keuken2 volumio[10762]: 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 04 02:24:27 keuken2 volumio[10762]: info: CoreCommandRouter::volumioGetState Mar 04 02:24:27 keuken2 volumio[10762]: info: CorePlayQueue::getTrack 0 Mar 04 02:24:27 keuken2 volumio[10762]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Mar 04 02:24:28 keuken2 volumio[10762]: verbose: New Socket.io Connection to 192.168.178.53:3000 from 192.168.178.34 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 04 02:24:28 keuken2 volumio[10762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 04 02:24:28 keuken2 volumio[10762]: info: CoreCommandRouter::volumioGetState Mar 04 02:24:28 keuken2 volumio[10762]: info: CorePlayQueue::getTrack 0 Mar 04 02:24:29 keuken2 go-librespot[20104]: time="2025-03-04T02:24:29+01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 02:24:29 keuken2 go-librespot[20104]: time="2025-03-04T02:24:29+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 02:24:29 keuken2 go-librespot[20104]: time="2025-03-04T02:24:29+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 02:24:29 keuken2 go-librespot[20104]: time="2025-03-04T02:24:29+01:00" level=info msg="zeroconf server listening on port 40833" Mar 04 02:24:30 keuken2 volumio[10762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 02:24:30 keuken2 volumio[10762]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Mar 04 02:24:30 keuken2 volumio[10762]: code: 'auth/network-request-failed', Mar 04 02:24:30 keuken2 volumio[10762]: a: null Mar 04 02:24:30 keuken2 volumio[10762]: } Mar 04 02:24:30 keuken2 volumio[10762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 02:24:30 keuken2 volumiossh-tunnel[20143]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Mar 04 02:24:32 keuken2 sudo[20158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-04 02:23 Mar 04 02:24:32 keuken2 sudo[20158]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"