-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Tue 2025-02-25 14:56:44 CET. -- Feb 25 14:55:02 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:55:02 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:02 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:55:02 okuns volumio[874]: (node:874) 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: 114) Feb 25 14:55:02 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:55:02 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:02 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:02 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:55:02 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:55:02 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:02 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 336. Feb 25 14:55:02 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:02 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:02 okuns go-librespot[5478]: Librespot-go daemon starting... Feb 25 14:55:02 okuns go-librespot[5478]: time="2025-02-25T14:55:02+01:00" level=info msg="generated new device id: 85424ad2d752d4ac78e0562cfdd2d77ae3f7439e" Feb 25 14:55:02 okuns go-librespot[5478]: time="2025-02-25T14:55:02+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:02 okuns go-librespot[5478]: time="2025-02-25T14:55:02+01:00" level=debug msg="obtained new client token: AABNgnhRv5zXhsacreXrV3n5T6dikIgM+90koOJ0enk0Mgx0tzp19n/Q9QUM8gdSltwAKQHDugcoUuVOgEuZjdPMOCrej7Y4HkSVN3PRj/iaHNcIqeVEkeJehu38zRKDHySaRvQVmYN00uLbBtZcn++S4MCOybSMgaSZdKBRuuRzRiGr0IZBHzwRE9hIvIApz6i1GFjLR7kp8mXzA5/thgoZLZeRYXtFezZcQjnPDFXnNu0Gv2HVA5Swksaw/Q==" Feb 25 14:55:02 okuns go-librespot[5478]: time="2025-02-25T14:55:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:02 okuns go-librespot[5478]: time="2025-02-25T14:55:02+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:03 okuns go-librespot[5478]: time="2025-02-25T14:55:03+01:00" level=debug msg="completed challenge" Feb 25 14:55:03 okuns go-librespot[5478]: time="2025-02-25T14:55:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:03 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:03 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:05 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:05 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:06 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:06 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 337. Feb 25 14:55:06 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:06 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:06 okuns go-librespot[5485]: Librespot-go daemon starting... Feb 25 14:55:06 okuns go-librespot[5485]: time="2025-02-25T14:55:06+01:00" level=info msg="generated new device id: 3e767c4ba66046813d785063d075923b063bebc1" Feb 25 14:55:06 okuns go-librespot[5485]: time="2025-02-25T14:55:06+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:06 okuns go-librespot[5485]: time="2025-02-25T14:55:06+01:00" level=debug msg="obtained new client token: AABLOgAFflQjcYDmb+g+d+mc3f//nXFtafAjL9hxzlQv2aFGHsnrYBL76NiCosqF7V7/D7bwsWAM/Z7lUieo/3ANBoJml7nf0pxRf2CuJI9eO7P1/+gfbIht9azjabStFnYIAWVSU9pj2VnNyeHRae4ledUDmuehgA2r6g4VA1Liqvllq2W9wmLkBv1hNe2B6G7/KzWyX9CDayAm4czIfd54x2usGXSYQDbM1wowkpJLfvZiDRf7CERw1aLbAw==" Feb 25 14:55:07 okuns go-librespot[5485]: time="2025-02-25T14:55:07+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:07 okuns go-librespot[5485]: time="2025-02-25T14:55:07+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:07 okuns go-librespot[5485]: time="2025-02-25T14:55:07+01:00" level=debug msg="completed challenge" Feb 25 14:55:07 okuns go-librespot[5485]: time="2025-02-25T14:55:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:07 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:07 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:08 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:08 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:10 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:10 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 338. Feb 25 14:55:10 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:10 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:10 okuns go-librespot[5492]: Librespot-go daemon starting... Feb 25 14:55:10 okuns go-librespot[5492]: time="2025-02-25T14:55:10+01:00" level=info msg="generated new device id: c1083c4f466bdb562ec5f4435e16051eab4e4552" Feb 25 14:55:10 okuns go-librespot[5492]: time="2025-02-25T14:55:10+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=debug msg="obtained new client token: AADgeWB0rQJzwMY82ulobdcdPdj4J9gQCkxTgC8d6o4qkL/MjMgRDOObn1MrdAxL2Qdw2rkZwWPpZR/xqfkXTdPFvzalffSj/R8f5P4t9Wu7qOZEKXX5F+Gq/LpMqLXJ2dElTZC+n9TonWJWUd4pffCmD4BV5ohlprn+RMCnQGguYEsAdV5jY9HQshFz73WHdvCDa5XzBccVCAAVFSHSXUkOAtgWNdRVYK4iU/2eZdse3LS7tGfphACc8fk=" Feb 25 14:55:11 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:11 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=debug msg="new websocket client" Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=debug msg="completed challenge" Feb 25 14:55:11 okuns go-librespot[5492]: time="2025-02-25T14:55:11+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:11 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:11 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:11 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:55:14 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:55:14 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:14 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:55:14 okuns volumio[874]: (node:874) 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: 115) Feb 25 14:55:14 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:55:14 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:55:14 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:55:14 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:14 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:15 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:15 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 339. Feb 25 14:55:15 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:15 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:15 okuns go-librespot[5500]: Librespot-go daemon starting... Feb 25 14:55:15 okuns go-librespot[5500]: time="2025-02-25T14:55:15+01:00" level=info msg="generated new device id: 0614fb7c4ac5307167e872a3b5f5f932bb09dd36" Feb 25 14:55:15 okuns go-librespot[5500]: time="2025-02-25T14:55:15+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:15 okuns go-librespot[5500]: time="2025-02-25T14:55:15+01:00" level=debug msg="obtained new client token: AAAtvseHdMNPShWNtvEMWCADVMIbygpXXGAGflb8iywnYx52rOqstH053z5IxIXi+s+c2AQ/55s0di85dTL73mFsBosxZe9AKFKV6fgCrZcBJ7HkS3SR3xwpu3m5LMEtlbjDLqWgUDYynC1hTrtrmhc2SwtI/WUKFbz5rmyrkrPsWa9v6SswTCz1wj3pYr3s/3N0Qhqpt1XSaRT5v3KBOXWUmhXPdRiWlIhSeBTPqZbdx8fIVu6XbN/f05pb9g==" Feb 25 14:55:15 okuns go-librespot[5500]: time="2025-02-25T14:55:15+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 14:55:15 okuns go-librespot[5500]: time="2025-02-25T14:55:15+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:16 okuns go-librespot[5500]: time="2025-02-25T14:55:16+01:00" level=debug msg="completed challenge" Feb 25 14:55:16 okuns go-librespot[5500]: time="2025-02-25T14:55:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:16 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:16 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:17 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:17 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:19 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:19 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 340. Feb 25 14:55:19 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:19 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:19 okuns go-librespot[5549]: Librespot-go daemon starting... Feb 25 14:55:19 okuns go-librespot[5549]: time="2025-02-25T14:55:19+01:00" level=info msg="generated new device id: 3d0de446ea7cc3bf03ce9ed521b4fbfc2eeeb5be" Feb 25 14:55:19 okuns go-librespot[5549]: time="2025-02-25T14:55:19+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:19 okuns go-librespot[5549]: time="2025-02-25T14:55:19+01:00" level=debug msg="obtained new client token: AAAUBxM1zW4bVohhqbsTvua1xJrdZ3kz1ZMrFGFr5KLWx2uB+09J8oGLs2S2e6+xujXZZYkWOUzHV7v6pNZzor2Imx+VveGtVpqHyj0MZ7+oFDf1cbv8sWiM1jYtP3HtaIz0pkkxuKHbY9IdDOD2ZPCfgKL9Oqp3ZnEpAP20NWWDrh2CHKwbeNGX2SA+LgUkHLNTRjP8DRDn8e0jU+VT6tzvyoqGBmt6tXUYLFB9x6XUu5sTaIUub5srfvqMfQ==" Feb 25 14:55:19 okuns go-librespot[5549]: time="2025-02-25T14:55:19+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]" Feb 25 14:55:19 okuns go-librespot[5549]: time="2025-02-25T14:55:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Feb 25 14:55:19 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:19 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:20 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:20 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:23 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:23 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 341. Feb 25 14:55:23 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:23 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:23 okuns go-librespot[5558]: Librespot-go daemon starting... Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=info msg="generated new device id: c185bc88e3707247f6be26bd06745f1aa3f3a2e4" Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=debug msg="obtained new client token: AAAwQ9H0Jb5gsl0UiTRFI4fbqvbMwe6LDA1/QRNtv9KnT5i5ZkJczrZsA5glf+KDay1XnVLxZJw+ChDu63vldzWz3xQvy+jdg1VgdBOMcErvD85640iFTXa1u92aIPsUex2UV4bSR0y7DsJP67MYDnEI2olgqllw2iuGgPmaB40nZn2jiKZAo3vlFhsvlQ0Gf20M47pd+A7KNmh6uM41j+WrowQ6DbcCqrjzW3pZ9vvb8C7IYYydLcqw/YmzLg==" Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:23 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:23 okuns go-librespot[5558]: time="2025-02-25T14:55:23+01:00" level=debug msg="new websocket client" Feb 25 14:55:23 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:55:24 okuns go-librespot[5558]: time="2025-02-25T14:55:24+01:00" level=debug msg="completed challenge" Feb 25 14:55:24 okuns go-librespot[5558]: time="2025-02-25T14:55:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:24 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:55:24 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:24 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:26 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:55:26 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:26 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:55:26 okuns volumio[874]: (node:874) 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: 116) Feb 25 14:55:27 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:55:27 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:55:27 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:55:27 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:27 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:27 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:27 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 342. Feb 25 14:55:27 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:27 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:27 okuns go-librespot[5566]: Librespot-go daemon starting... Feb 25 14:55:27 okuns go-librespot[5566]: time="2025-02-25T14:55:27+01:00" level=info msg="generated new device id: ff1ddb3db332bb1a9eb9bab424ce77ec7d8f7c6b" Feb 25 14:55:27 okuns go-librespot[5566]: time="2025-02-25T14:55:27+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:27 okuns go-librespot[5566]: time="2025-02-25T14:55:27+01:00" level=debug msg="obtained new client token: AACQRl027oXD2dR/zvuCjGO6Zh4UryPrGxRrbbIzmhSr2k1yP7PEVGi/Cgr5B+R4oa9dKsRHYQm317MEHaTs21oSR0iqiTNUie046sC/tXbmrJ3TbneftqvjWLcwV90yqHLstMwnelloJeKlut5QQ0V6OX8iM4ASEewUgtZ+jBg/ORYzFmDbwRMsvcb2Iyodpn7PWv4iLSei4jB0dai7iQ2B3l57vStS2nBF/3I27u09TING16Jx3wxXWSq6Rg==" Feb 25 14:55:27 okuns go-librespot[5566]: time="2025-02-25T14:55:27+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 14:55:27 okuns go-librespot[5566]: time="2025-02-25T14:55:27+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:28 okuns go-librespot[5566]: time="2025-02-25T14:55:28+01:00" level=debug msg="completed challenge" Feb 25 14:55:29 okuns go-librespot[5566]: time="2025-02-25T14:55:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:29 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:29 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:30 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:30 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:32 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:32 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 343. Feb 25 14:55:32 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:32 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:32 okuns go-librespot[5573]: Librespot-go daemon starting... Feb 25 14:55:32 okuns go-librespot[5573]: time="2025-02-25T14:55:32+01:00" level=info msg="generated new device id: f302b3ef921e2bc0224e2d83723993f4b49a1acb" Feb 25 14:55:32 okuns go-librespot[5573]: time="2025-02-25T14:55:32+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:32 okuns go-librespot[5573]: time="2025-02-25T14:55:32+01:00" level=debug msg="obtained new client token: AADgWh9/+7uKswKTnyQM+4b2x8YFKCoWA6yrozyYG/xg/MSmpgsuKnVAQDPfzeqwGN2Bg6m0nIMTfGbShs+U4KbLl9Of6inaIQjpdO9oFKvw1uJy7LMGqcwXL0+dzfd6REcVcm7Q+vvbI1JswYVoY+DQrlUVVMRVc8MaPgM3vDe8q73CfB0YiQrXaEkY9xFZl5PoO3F8wyaOEwQqQwmI4ZjyjIn/P3xVkugoj7XnC4y6CL5gl6d8plJmqxNotg==" Feb 25 14:55:32 okuns go-librespot[5573]: time="2025-02-25T14:55:32+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:32 okuns go-librespot[5573]: time="2025-02-25T14:55:32+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:33 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:33 okuns go-librespot[5573]: time="2025-02-25T14:55:33+01:00" level=debug msg="new websocket client" Feb 25 14:55:33 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:55:33 okuns go-librespot[5573]: time="2025-02-25T14:55:33+01:00" level=debug msg="completed challenge" Feb 25 14:55:33 okuns go-librespot[5573]: time="2025-02-25T14:55:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:33 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:33 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:33 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:55:36 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:55:36 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:36 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:55:36 okuns volumio[874]: (node:874) 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: 117) Feb 25 14:55:36 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:55:36 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:55:36 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:55:36 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:36 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:36 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:36 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 344. Feb 25 14:55:36 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:36 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:36 okuns go-librespot[5582]: Librespot-go daemon starting... Feb 25 14:55:36 okuns go-librespot[5582]: time="2025-02-25T14:55:36+01:00" level=info msg="generated new device id: 4479f0cf65156b4cbeca028d9550d4de40e2677b" Feb 25 14:55:36 okuns go-librespot[5582]: time="2025-02-25T14:55:36+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:36 okuns go-librespot[5582]: time="2025-02-25T14:55:36+01:00" level=debug msg="obtained new client token: AADO0Ow9uy1ElCY3rhOGSE0ndrBXlXE04RLDiV7253XXl/4JYc1mqItGGMcFXOrjGEYSNWq+8WObTcLdf2qXdjiIIDWobtEeZkv5GXzPyUhyTprMDT9KaZ99kEI2HhnJ+xZmiAnmnFEwrgoDCzR8YpcBbNcqKjLtPAuwuIjSELpFwdnLUgur4uF00WV9wEkQR02UnogqJgkgEc7fm84d0fpA9iK+75J7aNgYMvLQTWLcWGsnIDdoSpVqJ1iZbw==" Feb 25 14:55:37 okuns go-librespot[5582]: time="2025-02-25T14:55:37+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 14:55:37 okuns go-librespot[5582]: time="2025-02-25T14:55:37+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:37 okuns go-librespot[5582]: time="2025-02-25T14:55:37+01:00" level=debug msg="completed challenge" Feb 25 14:55:37 okuns go-librespot[5582]: time="2025-02-25T14:55:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:37 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:37 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:39 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:39 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:40 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:40 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 345. Feb 25 14:55:40 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:40 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:40 okuns go-librespot[5590]: Librespot-go daemon starting... Feb 25 14:55:40 okuns go-librespot[5590]: time="2025-02-25T14:55:40+01:00" level=info msg="generated new device id: 02ba9a6f7b1ddecc82133f4b469a12bcb9b2c23c" Feb 25 14:55:40 okuns go-librespot[5590]: time="2025-02-25T14:55:40+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:41 okuns go-librespot[5590]: time="2025-02-25T14:55:41+01:00" level=debug msg="obtained new client token: AABXhLZOVQazhd1BV3q+TEfB3UzGWTIdYujsRf15WPyqMBA+y1SRCZ4ruv/Jq8FvR49iGYfraRIiOVe5Sji0Y6fX5slbYPe7xOYkULprRsr50W/KehjUwdZRbgCnFgiGH0FweKoVyAJpFAjK/TWFjRCKA+rsmufY2J022TysbZBfhKxia0JIbdxhdSuOL2MZgUhiO7xdHhG44ejh6D0DTSBiU7fUBBug8g1fZZMO018Vq+AvJaVzaB33YaA=" Feb 25 14:55:41 okuns go-librespot[5590]: time="2025-02-25T14:55:41+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]" Feb 25 14:55:41 okuns go-librespot[5590]: time="2025-02-25T14:55:41+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:41 okuns go-librespot[5590]: time="2025-02-25T14:55:41+01:00" level=debug msg="completed challenge" Feb 25 14:55:41 okuns go-librespot[5590]: time="2025-02-25T14:55:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:41 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:41 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:42 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:42 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:45 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:45 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 346. Feb 25 14:55:45 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:45 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:45 okuns go-librespot[5598]: Librespot-go daemon starting... Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=info msg="generated new device id: c938578e2f7688383a41e0ce33662118296b3988" Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:45 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=debug msg="new websocket client" Feb 25 14:55:45 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=debug msg="obtained new client token: AACY2cTpMGtlMvrvooihPHvCzXBXh6y/5RswhpJbqqpejW/LpepyefNw1RqU/t+XEFm/Rb9TCb2m2nSQW1zBfD8E3gpeiW+COkJGPPOO7YaPxN+4+tjPUul+Q/9m6NmpByy8mAGXLfsohuQoqKhSqn3cA5FMH+vyHCNORaDQGAUfyzwXB5vGpTPZohR43xWeRAU93xu6n8/EfDC/e5WhiTLu7P2v58xHrWT8LKGBX5DyP+8smXoPX4WV68bxPA==" Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:55:45 okuns go-librespot[5598]: time="2025-02-25T14:55:45+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:46 okuns go-librespot[5598]: time="2025-02-25T14:55:46+01:00" level=debug msg="completed challenge" Feb 25 14:55:46 okuns go-librespot[5598]: time="2025-02-25T14:55:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:46 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:46 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:46 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:55:48 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:55:48 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:48 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:55:48 okuns volumio[874]: (node:874) 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: 118) Feb 25 14:55:48 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:55:48 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:55:48 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:55:49 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:49 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:49 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:49 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 347. Feb 25 14:55:49 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:49 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:49 okuns go-librespot[5606]: Librespot-go daemon starting... Feb 25 14:55:49 okuns go-librespot[5606]: time="2025-02-25T14:55:49+01:00" level=info msg="generated new device id: e19e74d9701e6786107511c87359a32f601385a5" Feb 25 14:55:49 okuns go-librespot[5606]: time="2025-02-25T14:55:49+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:49 okuns go-librespot[5606]: time="2025-02-25T14:55:49+01:00" level=debug msg="obtained new client token: AAA+fSmbkXIFssYWCSy3HIpHDGBqzwI/wL7Bv6WG1EOktYAB75qXRAc3XMJ2DTlq3sK9zEKkarP0rwqXQE+9aIlIaZQUak9PdSMYzTF3neuw/zYrRU1b9TOKlIOekr6v5VXcnXwGaGCVk08vh8ZbqiBtCUg3KFd/fA30ZhXErZkl/CiuOit3veUJbw89O/ZI5jhCiWXpODCA+5l57aA3DTpgcNxki07sV/7QkvaPsnGOOvgpsQeZHyMP+eebLA==" Feb 25 14:55:49 okuns go-librespot[5606]: time="2025-02-25T14:55:49+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 14:55:49 okuns go-librespot[5606]: time="2025-02-25T14:55:49+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:50 okuns go-librespot[5606]: time="2025-02-25T14:55:50+01:00" level=debug msg="completed challenge" Feb 25 14:55:50 okuns go-librespot[5606]: time="2025-02-25T14:55:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:50 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:50 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:52 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:52 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:53 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:53 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 348. Feb 25 14:55:53 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:53 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:53 okuns go-librespot[5614]: Librespot-go daemon starting... Feb 25 14:55:53 okuns go-librespot[5614]: time="2025-02-25T14:55:53+01:00" level=info msg="generated new device id: 18b61846cc3353dc4557cb7ac6ed077338a5a9b0" Feb 25 14:55:53 okuns go-librespot[5614]: time="2025-02-25T14:55:53+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:53 okuns go-librespot[5614]: time="2025-02-25T14:55:53+01:00" level=debug msg="obtained new client token: AADEFThtbMEqZ0yFO4t2j3KidIzqnEzP3WgDJDnSYvoLHehcEXjmyDHeey7r4YQBhgg2bX5JWLtN0GkjQuF9TWJHxrhxrBd/RuJvvePGKVF2NelHoinOrGvSjQL+6/N1Y2QFi9sc4oMsJPvaVxPVl9cY6++OMI5JbmFkKPMfPcWNDMlDkwo+a69VoyHbIKHmZaCaE9O9R2dEkE4h1WosKtlGBSwYDH15MFzuhlnact5m7EqMgX32seEDvi8CIA==" Feb 25 14:55:54 okuns go-librespot[5614]: time="2025-02-25T14:55:54+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]" Feb 25 14:55:54 okuns go-librespot[5614]: time="2025-02-25T14:55:54+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:54 okuns go-librespot[5614]: time="2025-02-25T14:55:54+01:00" level=debug msg="completed challenge" Feb 25 14:55:54 okuns go-librespot[5614]: time="2025-02-25T14:55:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:54 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:54 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:55 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:55 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:55:57 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:55:57 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 349. Feb 25 14:55:57 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:55:57 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:55:57 okuns go-librespot[5622]: Librespot-go daemon starting... Feb 25 14:55:57 okuns go-librespot[5622]: time="2025-02-25T14:55:57+01:00" level=info msg="generated new device id: 8e45b92b05bacddb2420289aa63ca47f6f5b7339" Feb 25 14:55:57 okuns go-librespot[5622]: time="2025-02-25T14:55:57+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+01:00" level=debug msg="obtained new client token: AAB2s+J7S6eIWjsTz0mbht+q2u1LEmmqqQAJx2clWdGYhXOzYkI/Eh+xbPtxVM5apmnfRVraHYyj/N2+qFBDYAnuC20IhDBx4ro5y7Lw/Vr4ChywNjhJZ3mgr5gaWzDeU3budL5qmWKHJh0/H8xYkjnxd6tR0bRsBCdfeD+c/OoldCYtDNi9UdyL265KtNTphA3Y4u3Lg0WBPzYSIQ/9aBprmgb3fNhA2Po30w5164WikWR5Qq4d5g/fw4Q=" Feb 25 14:55:58 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:55:58 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+01:00" level=debug msg="new websocket client" Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+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]" Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+01:00" level=debug msg="completed keyexchange" Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+01:00" level=debug msg="completed challenge" Feb 25 14:55:58 okuns go-librespot[5622]: time="2025-02-25T14:55:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:55:58 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:55:58 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:55:58 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:56:01 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:56:01 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:01 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:56:01 okuns volumio[874]: (node:874) 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: 119) Feb 25 14:56:01 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:56:01 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:56:01 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:56:01 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:01 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:02 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:02 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 350. Feb 25 14:56:02 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:02 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:02 okuns go-librespot[5629]: Librespot-go daemon starting... Feb 25 14:56:02 okuns go-librespot[5629]: time="2025-02-25T14:56:02+01:00" level=info msg="generated new device id: fbc91c3edceb811603dc5060627b07665d15b93b" Feb 25 14:56:02 okuns go-librespot[5629]: time="2025-02-25T14:56:02+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:02 okuns go-librespot[5629]: time="2025-02-25T14:56:02+01:00" level=debug msg="obtained new client token: AAAV2Pdk/knAHXtqUb+OdUCfN0vwJ7hgwrc+QiVnWNQj77IX7nOiui+z27WKaafFZRnSz37btpr0mrNDEtoSesuml1L2JdJCSDHdkRk6Fpn2Wathhb7Fr2CVMPiMNLiu37BJ9+qngPhCxiQ6biLz15hE+b7FmkBxXlvZYd/aH5t2b148QNaF0+UnU5jcarDCWN+vw4pcZDikvpaffQf5MUd+I3UEyJj3F7Iae1xD0/s902xXTgnqidia4JZH8A==" Feb 25 14:56:02 okuns go-librespot[5629]: time="2025-02-25T14:56:02+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 14:56:02 okuns go-librespot[5629]: time="2025-02-25T14:56:02+01:00" level=debug msg="completed keyexchange" Feb 25 14:56:03 okuns go-librespot[5629]: time="2025-02-25T14:56:03+01:00" level=debug msg="completed challenge" Feb 25 14:56:03 okuns go-librespot[5629]: time="2025-02-25T14:56:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:56:03 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:03 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:04 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:04 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:06 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:06 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 351. Feb 25 14:56:06 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:06 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:06 okuns go-librespot[5638]: Librespot-go daemon starting... Feb 25 14:56:06 okuns go-librespot[5638]: time="2025-02-25T14:56:06+01:00" level=info msg="generated new device id: 4bdf166dca1cc6c60bfd7ae3c20de69db2662410" Feb 25 14:56:06 okuns go-librespot[5638]: time="2025-02-25T14:56:06+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:06 okuns go-librespot[5638]: time="2025-02-25T14:56:06+01:00" level=debug msg="obtained new client token: AACFIrGMssrD4uI0aXws/2bDgYG3pmPDv4G6xQNuYXm92h3yB33XUOJpBypk9zA2wq++mc/aYoW9k/nvw29009Te5Xrdyxo/vIDK6BG7QWLJpjgShnpF2FEiXblMgcxc4nU8UCXSYjCp6Fl7Of7MiStbTAEf2QY/m7U3OqKd4p8rzDVIOX9cj2U5mahGVo+nF0afeg7dAEwddWzrtIwouVfkQ+U/jOG2ONx9FSkhLUEHzpasBeRzSne9UGr87w==" Feb 25 14:56:06 okuns go-librespot[5638]: time="2025-02-25T14:56:06+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]" Feb 25 14:56:06 okuns go-librespot[5638]: time="2025-02-25T14:56:06+01:00" level=debug msg="completed keyexchange" Feb 25 14:56:07 okuns go-librespot[5638]: time="2025-02-25T14:56:07+01:00" level=debug msg="completed challenge" Feb 25 14:56:07 okuns go-librespot[5638]: time="2025-02-25T14:56:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:56:07 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:07 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:07 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:07 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:10 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:10 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 352. Feb 25 14:56:10 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:10 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:10 okuns go-librespot[5645]: Librespot-go daemon starting... Feb 25 14:56:10 okuns go-librespot[5645]: time="2025-02-25T14:56:10+01:00" level=info msg="generated new device id: 1fc79b391c75b6dc3355f013e327e397a328252c" Feb 25 14:56:10 okuns go-librespot[5645]: time="2025-02-25T14:56:10+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:10 okuns go-librespot[5645]: time="2025-02-25T14:56:10+01:00" level=debug msg="obtained new client token: AACcciscJduGALpFI0FPy5Gc4PGnGBD41xkeG/YAKnqSb+1PyTGXMSl0igU10MnmluTg7ZF/3Hs9VbNQdhQdP7+v1txHjitJPo8zg0/npiBRya/lsvPGnYAC7I3D/F0l8vTpPGqmZ02QTH+1RNJDu9dBJDpxY8RBEz+UDLbbIEYuh682h34EpM/RBXNF9LaSFYu1xZfE2Ah/soC+NOqc1GTKS4R99ctTuqtC6wYBkR3xpNuBSLzEPb9ghCFTCw==" Feb 25 14:56:10 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:10 okuns go-librespot[5645]: time="2025-02-25T14:56:10+01:00" level=debug msg="new websocket client" Feb 25 14:56:11 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:56:11 okuns go-librespot[5645]: time="2025-02-25T14:56:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 14:56:11 okuns go-librespot[5645]: time="2025-02-25T14:56:11+01:00" level=debug msg="completed keyexchange" Feb 25 14:56:11 okuns go-librespot[5645]: time="2025-02-25T14:56:11+01:00" level=debug msg="completed challenge" Feb 25 14:56:11 okuns go-librespot[5645]: time="2025-02-25T14:56:11+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:56:11 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:11 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:11 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:56:14 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:56:14 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:14 okuns volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 25 14:56:14 okuns volumio[874]: (node:874) 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: 120) Feb 25 14:56:14 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:56:14 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:56:14 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:56:14 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:14 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:14 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:14 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 353. Feb 25 14:56:14 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:14 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:14 okuns go-librespot[5653]: Librespot-go daemon starting... Feb 25 14:56:14 okuns go-librespot[5653]: time="2025-02-25T14:56:14+01:00" level=info msg="generated new device id: b92b5e2574369b22eae9f44e6f3c3a92c73b24b0" Feb 25 14:56:14 okuns go-librespot[5653]: time="2025-02-25T14:56:14+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:15 okuns go-librespot[5653]: time="2025-02-25T14:56:15+01:00" level=debug msg="obtained new client token: AACKf6PWaICvIDXpaOZpjqmcBZSa6+jVyXQ7/r+n5d3u6SjF7TQ5FtuKpUotNdh2Q5pR9qz9SSFhIdkeq124S9++SpAwbaPtefe/3v8RMxePT2cgDSAmKN0RU4jplAxL4wrGODr+OM4VFrdzchMbRq3EQ4E/0DJpH8juB0VtmMz0kvkmlU3XwtHVYYzE0PwpREmd/c97zBp7BhsIx4k96lGvBl8c4/BIYNXmd3eLznae2UAUfEUJiLZGKKs=" Feb 25 14:56:15 okuns go-librespot[5653]: time="2025-02-25T14:56:15+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]" Feb 25 14:56:15 okuns go-librespot[5653]: time="2025-02-25T14:56:15+01:00" level=debug msg="completed keyexchange" Feb 25 14:56:15 okuns go-librespot[5653]: time="2025-02-25T14:56:15+01:00" level=debug msg="completed challenge" Feb 25 14:56:15 okuns go-librespot[5653]: time="2025-02-25T14:56:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:56:15 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:15 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:17 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:17 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:19 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:19 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 354. Feb 25 14:56:19 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:19 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:19 okuns go-librespot[5703]: Librespot-go daemon starting... Feb 25 14:56:19 okuns go-librespot[5703]: time="2025-02-25T14:56:19+01:00" level=info msg="generated new device id: 41093621cd46e0eff265c1916d56914145467328" Feb 25 14:56:19 okuns go-librespot[5703]: time="2025-02-25T14:56:19+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:19 okuns go-librespot[5703]: time="2025-02-25T14:56:19+01:00" level=debug msg="obtained new client token: AADehchgBR8Esf5x5R4hhAiJneR0BiArBS4osl4D+zRNiVFoo8gZYnXiqoeIc75SNrrcw7HjZ74vNZzk/g1CFfevNFVb/pmE4FpKVwbRubCziN0fTiOpoch8nfHQK8diEllWfyX+ELXGQZWY0gTVbnzyz9faZzHZJBqFSSdHmFPvZM5yx3zQIppLwdlEAlFtRFES4Tx6Dw7qeEpLCuY36f3lXqTefg1DnMdMtdornjRD8YQzv0VF/4gc1CzmXA==" Feb 25 14:56:19 okuns go-librespot[5703]: time="2025-02-25T14:56:19+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]" Feb 25 14:56:19 okuns go-librespot[5703]: time="2025-02-25T14:56:19+01:00" level=debug msg="completed keyexchange" Feb 25 14:56:20 okuns go-librespot[5703]: time="2025-02-25T14:56:20+01:00" level=debug msg="completed challenge" Feb 25 14:56:20 okuns go-librespot[5703]: time="2025-02-25T14:56:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Feb 25 14:56:20 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:20 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:20 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:20 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:23 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:23 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 355. Feb 25 14:56:23 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:23 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:23 okuns go-librespot[5710]: Librespot-go daemon starting... Feb 25 14:56:23 okuns go-librespot[5710]: time="2025-02-25T14:56:23+01:00" level=info msg="generated new device id: 20ba585bb4a3d86f20ee4e61f30fa09d6a104d4e" Feb 25 14:56:23 okuns go-librespot[5710]: time="2025-02-25T14:56:23+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:23 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:23 okuns volumio[874]: info: Connection to go-librespot Websocket established Feb 25 14:56:23 okuns go-librespot[5710]: time="2025-02-25T14:56:23+01:00" level=debug msg="new websocket client" Feb 25 14:56:23 okuns go-librespot[5710]: time="2025-02-25T14:56:23+01:00" level=debug msg="obtained new client token: AAB+bWEBxzz0QKw/8B8hpWIg0JMPyN1NaZCiOmhLfQX3DcLtxHh1ect4DP8kBYFyzWhOAbBGvFGtOiks+55Cj7Kt6Ye1iBacTc7NBfm2K3UmM8KwVwO+8JjqKGeC/1AKzqfvF5TTdmN4l8M9Xs2428JwNMee3DzmoQsB+Td1xsJenspo2s5sOIlCcXHHZC2orKeH2HXx4diqOCASI7H0ziFFogZumjVmOxC9U0+XvNjD0vk/evBfBkFSxAOwQA==" Feb 25 14:56:26 okuns volumio[874]: info: Getting Spotify volume Feb 25 14:56:26 okuns volumio[874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 14:56:26 okuns volumio[874]: info: CoreCommandRouter::volumioGetState Feb 25 14:56:26 okuns volumio[874]: info: CorePlayQueue::getTrack 0 Feb 25 14:56:27 okuns dhcpcd[810]: wlan0: carrier lost Feb 25 14:56:27 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-DISCONNECTED bssid=14:33:75:dc:9c:a1 reason=0 locally_generated=1 Feb 25 14:56:27 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Feb 25 14:56:27 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NL Feb 25 14:56:27 okuns avahi-daemon[534]: Withdrawing address record for 192.168.1.139 on wlan0. Feb 25 14:56:27 okuns dhcpcd[810]: wlan0: deleting route to 192.168.1.0/24 Feb 25 14:56:27 okuns dhcpcd[810]: wlan0: deleting default route via 192.168.1.1 Feb 25 14:56:27 okuns avahi-daemon[534]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.139. Feb 25 14:56:27 okuns avahi-daemon[534]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 25 14:56:27 okuns volumio[874]: info: Discovery: A device disappeared from network Feb 25 14:56:27 okuns volumio[874]: info: Discovery: Device okuns disappeared from network Feb 25 14:56:28 okuns go-librespot[5710]: time="2025-02-25T14:56:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting accesspoint from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 25 14:56:28 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:28 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:28 okuns volumio[874]: (node:874) UnhandledPromiseRejectionWarning: Error: socket hang up Feb 25 14:56:28 okuns volumio[874]: at connResetException (internal/errors.js:607:14) Feb 25 14:56:28 okuns volumio[874]: at Socket.socketOnEnd (_http_client.js:493:23) Feb 25 14:56:28 okuns volumio[874]: at Socket.emit (events.js:327:22) Feb 25 14:56:28 okuns volumio[874]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 25 14:56:28 okuns volumio[874]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 25 14:56:28 okuns volumio[874]: (node:874) 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: 121) Feb 25 14:56:28 okuns volumio[874]: info: Connection to go-librespot Websocket closed Feb 25 14:56:30 okuns ntpd[704]: Deleting interface #3 wlan0, 192.168.1.139#123, interface stats: received=464, sent=468, dropped=0, active_time=1864 secs Feb 25 14:56:30 okuns ntpd[704]: 86.80.166.233 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 5.255.124.190 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 162.159.200.1 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 162.159.200.123 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 178.239.19.62 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 178.239.19.59 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 94.198.159.15 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 83.98.201.134 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 213.239.154.12 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 178.239.19.56 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 185.45.112.121 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 195.35.113.80 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 178.215.228.24 local addr 192.168.1.139 -> Feb 25 14:56:30 okuns ntpd[704]: 178.239.19.58 local addr 192.168.1.139 -> Feb 25 14:56:31 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:31 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:32 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:32 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 356. Feb 25 14:56:32 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:32 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:32 okuns go-librespot[5764]: Librespot-go daemon starting... Feb 25 14:56:32 okuns go-librespot[5764]: time="2025-02-25T14:56:32+01:00" level=info msg="generated new device id: 00f3eb485e542ba71dbd03f3d07835d125824b39" Feb 25 14:56:32 okuns go-librespot[5764]: time="2025-02-25T14:56:32+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:32 okuns go-librespot[5764]: time="2025-02-25T14:56:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 25 14:56:32 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:32 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:33 okuns wpa_supplicant[796]: wlan0: Trying to associate with SSID 'BIG J' Feb 25 14:56:34 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:34 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:34 okuns dhcpcd[810]: wlan0: carrier acquired Feb 25 14:56:34 okuns wpa_supplicant[796]: wlan0: Associated with d4:1a:d1:5f:af:ed Feb 25 14:56:34 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:1a:d1:5f:af:ed completed [id=0 id_str=] Feb 25 14:56:34 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 25 14:56:34 okuns wpa_supplicant[796]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL Feb 25 14:56:34 okuns dhcpcd[810]: wlan0: IAID 01:7b:02:2a Feb 25 14:56:34 okuns dhcpcd[810]: wlan0: carrier lost Feb 25 14:56:35 okuns dhcpcd[810]: wlan0: carrier acquired Feb 25 14:56:35 okuns dhcpcd[810]: wlan0: IAID 01:7b:02:2a Feb 25 14:56:35 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:35 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 357. Feb 25 14:56:35 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:35 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:35 okuns go-librespot[5784]: Librespot-go daemon starting... Feb 25 14:56:35 okuns go-librespot[5784]: time="2025-02-25T14:56:35+01:00" level=info msg="generated new device id: 1b1f6cdf67c910a09b3ba3a53eac80bc891fe4e9" Feb 25 14:56:35 okuns go-librespot[5784]: time="2025-02-25T14:56:35+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:35 okuns go-librespot[5784]: time="2025-02-25T14:56:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 25 14:56:35 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:35 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:35 okuns dhcpcd[810]: wlan0: soliciting a DHCP lease Feb 25 14:56:35 okuns dhcpcd[810]: wlan0: soliciting an IPv6 router Feb 25 14:56:37 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:37 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:38 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:38 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 358. Feb 25 14:56:38 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:38 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:38 okuns go-librespot[5797]: Librespot-go daemon starting... Feb 25 14:56:38 okuns go-librespot[5797]: time="2025-02-25T14:56:38+01:00" level=info msg="generated new device id: 1ec6022782f58ace0c4391131676725b6f7b7e12" Feb 25 14:56:38 okuns go-librespot[5797]: time="2025-02-25T14:56:38+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:38 okuns go-librespot[5797]: time="2025-02-25T14:56:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 25 14:56:38 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:38 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:40 okuns dhcpcd[810]: wlan0: probing for an IPv4LL address Feb 25 14:56:40 okuns volumio[874]: info: Initializing connection to go-librespot Websocket Feb 25 14:56:40 okuns volumio[874]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 14:56:41 okuns volumio[874]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 14:56:41 okuns volumio[874]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 25 14:56:41 okuns volumio[874]: at doSend (dgram.js:692:16) Feb 25 14:56:41 okuns volumio[874]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 25 14:56:41 okuns volumio[874]: at afterDns (dgram.js:638:5) Feb 25 14:56:41 okuns volumio[874]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 25 14:56:41 okuns volumio[874]: errno: -101, Feb 25 14:56:41 okuns volumio[874]: code: 'ENETUNREACH', Feb 25 14:56:41 okuns volumio[874]: syscall: 'send', Feb 25 14:56:41 okuns volumio[874]: address: '255.255.255.255', Feb 25 14:56:41 okuns volumio[874]: port: 3483 Feb 25 14:56:41 okuns volumio[874]: } Feb 25 14:56:41 okuns volumio[874]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 14:56:41 okuns systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 14:56:41 okuns systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 359. Feb 25 14:56:41 okuns systemd[1]: Stopped go-librespot Daemon. Feb 25 14:56:41 okuns systemd[1]: Started go-librespot Daemon. Feb 25 14:56:41 okuns go-librespot[5806]: Librespot-go daemon starting... Feb 25 14:56:41 okuns go-librespot[5806]: time="2025-02-25T14:56:41+01:00" level=info msg="generated new device id: 11f4cd103a0ca273d7d9e9f468886298c0d240ee" Feb 25 14:56:41 okuns go-librespot[5806]: time="2025-02-25T14:56:41+01:00" level=debug msg="stored credentials found for 31lrcanxtz3lqfx2cagcxmilikzy" Feb 25 14:56:41 okuns go-librespot[5806]: time="2025-02-25T14:56:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Feb 25 14:56:41 okuns systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 14:56:41 okuns systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 14:56:44 okuns sudo[5820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-25 14:55 Feb 25 14:56:44 okuns sudo[5820]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"