-- Logs begin at Sat 2025-01-11 19:24:41 CET, end at Sat 2025-01-11 20:37:27 CET. -- Jan 11 20:36:35 volumio go-librespot[20138]: time="2025-01-11T20:36:35+01:00" level=error msg="did not receive last pong ack from accesspoint, 120s passed" Jan 11 20:36:35 volumio go-librespot[20138]: time="2025-01-11T20:36:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.16:41360->104.199.65.9:4070: use of closed network connection" Jan 11 20:36:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:35 volumio volumio[818]: (node:818) UnhandledPromiseRejectionWarning: Error: socket hang up Jan 11 20:36:35 volumio volumio[818]: at connResetException (internal/errors.js:639:14) Jan 11 20:36:35 volumio volumio[818]: at Socket.socketOnEnd (_http_client.js:499:23) Jan 11 20:36:35 volumio volumio[818]: at Socket.emit (events.js:412:35) Jan 11 20:36:35 volumio volumio[818]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 11 20:36:35 volumio volumio[818]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 11 20:36:35 volumio volumio[818]: (node:818) 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: 99) Jan 11 20:36:35 volumio volumio[818]: info: Connection to go-librespot Websocket closed Jan 11 20:36:38 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:38 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 720. Jan 11 20:36:38 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:38 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:38 volumio go-librespot[20766]: Librespot-go daemon starting... Jan 11 20:36:38 volumio go-librespot[20766]: time="2025-01-11T20:36:38+01:00" level=info msg="generated new device id: a9e39364462debbdbb5f080c3087fc7fef375075" Jan 11 20:36:38 volumio go-librespot[20766]: time="2025-01-11T20:36:38+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:38 volumio go-librespot[20766]: time="2025-01-11T20:36:38+01:00" level=debug msg="obtained new client token: AADOIoynqpf0+iBisYIeDXsWXJvjpIyapYxqIufXS9IbOSaAzquDcrYdR4jCMcyYzcGiQ/A4f+gBs4bELDDu8LAw9YXC4H9URR/N3vRCNAbKj2feaN++JIha76DRPxKMRojDn0bT7BKA/UEzmmrNlmj4or2JEqtRItnGQtsTd5quigKbms7eD1GaVOd8/psjDUeGXtlDvXJIP1Pz4YIQB82wJsDwzEhSbcy9vo8JDx2RTsi4Wb+gEsIjrGx7lA==" Jan 11 20:36:38 volumio go-librespot[20766]: time="2025-01-11T20:36:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 20:36:39 volumio go-librespot[20766]: time="2025-01-11T20:36:39+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:39 volumio go-librespot[20766]: time="2025-01-11T20:36:39+01:00" level=debug msg="completed challenge" Jan 11 20:36:39 volumio go-librespot[20766]: time="2025-01-11T20:36:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:41 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:41 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 721. Jan 11 20:36:42 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:42 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:42 volumio go-librespot[20777]: Librespot-go daemon starting... Jan 11 20:36:42 volumio go-librespot[20777]: time="2025-01-11T20:36:42+01:00" level=info msg="generated new device id: 09e0e2c51992d39c55c41274b9864eebf6f04f9b" Jan 11 20:36:42 volumio go-librespot[20777]: time="2025-01-11T20:36:42+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:42 volumio go-librespot[20777]: time="2025-01-11T20:36:42+01:00" level=debug msg="obtained new client token: AABOFkNt83U55JdGa5Ob0XIYGt2Nt1S6hDWDti/qMx+0Zhjk8p2TOkrXPjACuqvOKHI/qNbjLokZJt673g4ghIYX6Sl8BwXdDnWILCrP6wSFRs3QI0Qj7z0VWP4lwpd/o/eULT06LYe1+T6bAayxwtGCiSEEY97upu7JP2deEiPv/vtfHIYtzicQelWc+8j8BAXhNGSh1B7s1lXHPZ5N5+k8lf+HK5znRoFu1Fsih5wJY1VBSzj8VUjbJ0HG8Q==" Jan 11 20:36:42 volumio go-librespot[20777]: time="2025-01-11T20:36:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:36:43 volumio go-librespot[20777]: time="2025-01-11T20:36:43+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:43 volumio go-librespot[20777]: time="2025-01-11T20:36:43+01:00" level=debug msg="completed challenge" Jan 11 20:36:43 volumio go-librespot[20777]: time="2025-01-11T20:36:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:44 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:44 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 722. Jan 11 20:36:46 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:46 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:46 volumio go-librespot[20832]: Librespot-go daemon starting... Jan 11 20:36:46 volumio go-librespot[20832]: time="2025-01-11T20:36:46+01:00" level=info msg="generated new device id: ca3a709d306f74a0c30b2b7360f8b1ab10bcb1b1" Jan 11 20:36:46 volumio go-librespot[20832]: time="2025-01-11T20:36:46+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:46 volumio go-librespot[20832]: time="2025-01-11T20:36:46+01:00" level=debug msg="obtained new client token: AAB/l0HIo4+rmv2HBjkJeZSagEBN/1MBKP1lhNJ6Ux+noFoBhWrYJYudAftXZXQ7MvBfNfumCMXZjczTpIld7+8gnE0m9XHFVJO4QKfshZvSfENoT1PFjqdKQFozmMgAs3VHkurqRTSX48fLojSecCsdrIetbUxjxWtNBMyyZ9NVoOjuFKPpF2ng6uxXQPlubCgoNUVyvRZToT7aOVeFDiXFqOZQEFYEhEChkwZ61lI/4OjQO6jVBgIquU6Olg==" Jan 11 20:36:46 volumio go-librespot[20832]: time="2025-01-11T20:36:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 20:36:47 volumio go-librespot[20832]: time="2025-01-11T20:36:47+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:47 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:47 volumio go-librespot[20832]: time="2025-01-11T20:36:47+01:00" level=debug msg="new websocket client" Jan 11 20:36:47 volumio go-librespot[20832]: time="2025-01-11T20:36:47+01:00" level=debug msg="completed challenge" Jan 11 20:36:47 volumio volumio[818]: info: Connection to go-librespot Websocket established Jan 11 20:36:47 volumio go-librespot[20832]: time="2025-01-11T20:36:47+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:47 volumio volumio[818]: info: Connection to go-librespot Websocket closed Jan 11 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:50 volumio volumio[818]: info: Getting Spotify volume Jan 11 20:36:50 volumio volumio[818]: (node:818) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:50 volumio volumio[818]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Jan 11 20:36:50 volumio volumio[818]: (node:818) 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: 100) Jan 11 20:36:50 volumio volumio[818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 11 20:36:50 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:36:50 volumio volumio[818]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Jan 11 20:36:50 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:50 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 723. Jan 11 20:36:50 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:50 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:50 volumio go-librespot[20858]: Librespot-go daemon starting... Jan 11 20:36:50 volumio go-librespot[20858]: time="2025-01-11T20:36:50+01:00" level=info msg="generated new device id: 90b019c26eee3e99651121f6d77303b088357755" Jan 11 20:36:50 volumio go-librespot[20858]: time="2025-01-11T20:36:50+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:50 volumio go-librespot[20858]: time="2025-01-11T20:36:50+01:00" level=debug msg="obtained new client token: AAA/H1SjjYK+zbsX76tNIgbUiPkQxrrEbDOUyWfOL9drK7rbLuB/jOok7DNAMW4dLB7b9GNCO7AWoiyqzFfUq/X2/jPSp/TYjNLvHSth9oaJl4YBMqPh8pPUK7YF96W1t04/PnWUggdxGcxe/QhV+eCwhyWSstHQvDC3TAgeGH/0gFFNxWS3PU+d/+vuPQJxhrKydQ5RBgJuOzynVk880jUfeiPa4NZKlKYm61DorFAcVuvv1OmfJr7OlfxBOg==" Jan 11 20:36:50 volumio go-librespot[20858]: time="2025-01-11T20:36:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:36:51 volumio go-librespot[20858]: time="2025-01-11T20:36:51+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:51 volumio go-librespot[20858]: time="2025-01-11T20:36:51+01:00" level=debug msg="completed challenge" Jan 11 20:36:51 volumio go-librespot[20858]: time="2025-01-11T20:36:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:53 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:53 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 724. Jan 11 20:36:54 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:54 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:54 volumio go-librespot[20867]: Librespot-go daemon starting... Jan 11 20:36:54 volumio go-librespot[20867]: time="2025-01-11T20:36:54+01:00" level=info msg="generated new device id: 5544335faf70c738ddaa2e4bdd536a0d161a2b4c" Jan 11 20:36:54 volumio go-librespot[20867]: time="2025-01-11T20:36:54+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:54 volumio go-librespot[20867]: time="2025-01-11T20:36:54+01:00" level=debug msg="obtained new client token: AABnEfVicb32JVmBZX5vmzfYb363NjAPVqYu/hIkqpqKshNo+BylPOeg9G6pl3oyS3dtN0lvWPQpz4j7aXpdykyvqcqagXwmFu2naNxNhrOH0owpFZhaHqfWL+7DhJ3/PcHkfD19hWfiNWbTjfoJQY55upOaMc7JJ+Vvn0jkEmrQzlnTVrjKQHwsl1HhZaGTvHND6M9NA5D+YOeHf0zfZQnJHEFbR19eaAyX0QtT5psx1CTm0hyDhS2Gr44Ucg==" Jan 11 20:36:54 volumio go-librespot[20867]: time="2025-01-11T20:36:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:36:55 volumio go-librespot[20867]: time="2025-01-11T20:36:55+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:55 volumio go-librespot[20867]: time="2025-01-11T20:36:55+01:00" level=debug msg="completed challenge" Jan 11 20:36:55 volumio go-librespot[20867]: time="2025-01-11T20:36:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:56 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:56 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:36:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:36:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 725. Jan 11 20:36:58 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:36:58 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:36:58 volumio go-librespot[20889]: Librespot-go daemon starting... Jan 11 20:36:58 volumio go-librespot[20889]: time="2025-01-11T20:36:58+01:00" level=info msg="generated new device id: 2c7ba19cf4fe45793035538bd636d725df2c8569" Jan 11 20:36:58 volumio go-librespot[20889]: time="2025-01-11T20:36:58+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:36:58 volumio go-librespot[20889]: time="2025-01-11T20:36:58+01:00" level=debug msg="obtained new client token: AAAvOeK+RRNUcbCRD7vbCGDnhKWt+FI6IouLzUVUQs0wIooOp6n/3xKpNut0gJ9HtDUxdLB5X7I2s7nSkGfIveSbi1Zjyz0Ci2yn0LtFZARkKSAD4dqL89U6Gr0OJY/N16qqSLZQitMWrMDvE9MlM6jyPIWReToZvqTWdpTmsqxh0KDimbe2ihP23rmnT36xd0OEI9zzRs2t3mmlxessHJTyrUXRrLLLfURdRYcSw3A4GG4S9+jycdUP5BI/Ig==" Jan 11 20:36:58 volumio go-librespot[20889]: time="2025-01-11T20:36:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:36:59 volumio go-librespot[20889]: time="2025-01-11T20:36:59+01:00" level=debug msg="completed keyexchange" Jan 11 20:36:59 volumio go-librespot[20889]: time="2025-01-11T20:36:59+01:00" level=debug msg="completed challenge" Jan 11 20:36:59 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:36:59 volumio go-librespot[20889]: time="2025-01-11T20:36:59+01:00" level=debug msg="new websocket client" Jan 11 20:36:59 volumio volumio[818]: info: Connection to go-librespot Websocket established Jan 11 20:36:59 volumio go-librespot[20889]: time="2025-01-11T20:36:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:36:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:36:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:36:59 volumio volumio[818]: info: Connection to go-librespot Websocket closed Jan 11 20:37:02 volumio volumio[818]: info: Getting Spotify volume Jan 11 20:37:02 volumio volumio[818]: (node:818) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:02 volumio volumio[818]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Jan 11 20:37:02 volumio volumio[818]: (node:818) 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: 101) Jan 11 20:37:02 volumio volumio[818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 11 20:37:02 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:02 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:02 volumio volumio[818]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Jan 11 20:37:02 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 726. Jan 11 20:37:02 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:02 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:02 volumio go-librespot[20900]: Librespot-go daemon starting... Jan 11 20:37:02 volumio go-librespot[20900]: time="2025-01-11T20:37:02+01:00" level=info msg="generated new device id: 2b98c30230a841b804017c960a69ae45c57be0d0" Jan 11 20:37:02 volumio go-librespot[20900]: time="2025-01-11T20:37:02+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:02 volumio go-librespot[20900]: time="2025-01-11T20:37:02+01:00" level=debug msg="obtained new client token: AAAKwU3kdBn0Z8C406Wtf4peoJ+wfh/2GPTSi2veQrCasBgyfeasrn6sue5H+VdzDBSfHUm4xyi9lLntpWttd1HPweMkguGjq87ScxnWqFDhU2mW/F43QWN+StNHm5AhBhaszmef+sRPVdxtgu8Wk4lW1aakj339+RcxCtKczYvs7N1g6ecIsmvSeM3ANeN8Z1dtoQv4Y4YBoJz35TUCDlX1MXHFq+4zMVGIdHQGaqjqrkshctufbZHeQsaZwA==" Jan 11 20:37:02 volumio go-librespot[20900]: time="2025-01-11T20:37:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:37:03 volumio go-librespot[20900]: time="2025-01-11T20:37:03+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:03 volumio go-librespot[20900]: time="2025-01-11T20:37:03+01:00" level=debug msg="completed challenge" Jan 11 20:37:03 volumio go-librespot[20900]: time="2025-01-11T20:37: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" Jan 11 20:37:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:05 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:05 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 727. Jan 11 20:37:06 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:06 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:06 volumio go-librespot[20908]: Librespot-go daemon starting... Jan 11 20:37:06 volumio go-librespot[20908]: time="2025-01-11T20:37:06+01:00" level=info msg="generated new device id: 10cd446f6d6e396c92bf648a8447c780314c59cd" Jan 11 20:37:06 volumio go-librespot[20908]: time="2025-01-11T20:37:06+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:06 volumio go-librespot[20908]: time="2025-01-11T20:37:06+01:00" level=debug msg="obtained new client token: AACkpHYi5e0ZGwl90MxS5zJgTPtzE/8o0Ax4Xe73NF4vS8iw1D95Pe/xO3MfjSqC5Ku2Obsy5OPGvo7xYpC25vFjsudj1c+H5aAGP6FOPXKrIL8+MkuspFjQTFznoRlmc1cZLLNHjdlLoiB41m6290sGExq7J2Fr88f5Un6NVJd3qHWjSL/ueaDQtYeC8k+RuFLY7jIijD2WbSlm4H/VwmC4NL4vTrK6pHNyG1NdRQKWzA3QBMqTbFtJK1/p7w==" Jan 11 20:37:06 volumio go-librespot[20908]: time="2025-01-11T20:37:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:37:07 volumio go-librespot[20908]: time="2025-01-11T20:37:07+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:07 volumio go-librespot[20908]: time="2025-01-11T20:37:07+01:00" level=debug msg="completed challenge" Jan 11 20:37:07 volumio go-librespot[20908]: time="2025-01-11T20:37: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" Jan 11 20:37:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:08 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:08 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 728. Jan 11 20:37:10 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:10 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:10 volumio go-librespot[20934]: Librespot-go daemon starting... Jan 11 20:37:10 volumio go-librespot[20934]: time="2025-01-11T20:37:10+01:00" level=info msg="generated new device id: 3bc209acc5d9fc40aa8bcdedfb112d24207afa43" Jan 11 20:37:10 volumio go-librespot[20934]: time="2025-01-11T20:37:10+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:10 volumio go-librespot[20934]: time="2025-01-11T20:37:10+01:00" level=debug msg="obtained new client token: AAAST78iisi8561QsmWWhtTcCr5r7GZhOSn2NoGDFe7YQe0fHTsmxMELsIIB6Ax4eZFU+KfCQ0xouVtt3E/rTxZNNlgLm+TNVnoM6V5t5Ga40gqph3sjg8mFzhqT7upZfV6Cy3qkMVdXLylZ1Ypdbo6UDJud5QRur2FV1HChDZPLZ7wiYhMqITzENVqOsEoZGr6Ytkd2nJw9SciQOGNc0RIj9PVteHWe/R+/kQt/4vNAN9c1NkJMZfRTYgnvNQ==" Jan 11 20:37:10 volumio go-librespot[20934]: time="2025-01-11T20:37:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:37:11 volumio go-librespot[20934]: time="2025-01-11T20:37:11+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:11 volumio go-librespot[20934]: time="2025-01-11T20:37:11+01:00" level=debug msg="completed challenge" Jan 11 20:37:11 volumio go-librespot[20934]: time="2025-01-11T20:37: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" Jan 11 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:11 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:11 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:14 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:14 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 729. Jan 11 20:37:14 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:14 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:14 volumio go-librespot[20943]: Librespot-go daemon starting... Jan 11 20:37:14 volumio go-librespot[20943]: time="2025-01-11T20:37:14+01:00" level=info msg="generated new device id: 7999e09af83f6320010161411a86a5715ff9af24" Jan 11 20:37:14 volumio go-librespot[20943]: time="2025-01-11T20:37:14+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:14 volumio go-librespot[20943]: time="2025-01-11T20:37:14+01:00" level=debug msg="obtained new client token: AAB2p98fw1jmBiZ4WE0/EqarBaO/ngNaDGFom2bwJthsLq+gpRSX+XVTI6RtbIzNLtupYyolAtoN7G2sK/gWVJO/Q+Yr0Fy9mhUl5vwyh9XnvkTjBmQ8iWu/0COCPZYtgd52+nK0H2+6iaYA2kd8YEkTDHdb0y0feYxCyzgbS0llnZSBn5CKMv6RsAQLE4QbKnu0kqqzL10uiEQGptlsh1jOlUpVxWLFOdrpqtkIVoZ4hldNWfkjx9wiXEQksg==" Jan 11 20:37:14 volumio go-librespot[20943]: time="2025-01-11T20:37:14+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 20:37:15 volumio go-librespot[20943]: time="2025-01-11T20:37:15+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:15 volumio go-librespot[20943]: time="2025-01-11T20:37:15+01:00" level=debug msg="completed challenge" Jan 11 20:37:15 volumio go-librespot[20943]: time="2025-01-11T20:37: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" Jan 11 20:37:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:37:16 volumio volumio[818]: info: Discovery: Getting this device information Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:37:16 volumio volumio[818]: verbose: New Socket.io Connection to 192.168.1.16:3000 from 192.168.1.22 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:37:16 volumio volumio[818]: info: Discovery: Getting this device information Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:16 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:37:17 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:17 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 730. Jan 11 20:37:18 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:18 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:18 volumio go-librespot[20965]: Librespot-go daemon starting... Jan 11 20:37:18 volumio go-librespot[20965]: time="2025-01-11T20:37:18+01:00" level=info msg="generated new device id: 3c04fbe6cc968ee83de860ccf03d314d265da850" Jan 11 20:37:18 volumio go-librespot[20965]: time="2025-01-11T20:37:18+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:18 volumio go-librespot[20965]: time="2025-01-11T20:37:18+01:00" level=debug msg="obtained new client token: AACMWi2Iw94Zp0wQE6iFTi2z1szVzToCB16c2Qq4x5PcK/gAWz90eei6Vs0y0zBSKB55AzqxD2KigVHv4a/AD/DDxtEAmrcHY/UDZJjyWAPxqWe2hZKZ6U/jwzQ/4X+Pdvj7GCyQ1mk+z7OTi4UJ1M1lfxVZmcoonnYNZC/JgkAnRRHxO1MdG3jnfKk68op7THCpL+647yyjOjMTqolHrzbYFS+clPYN7kC3JYPckyLy/oenNNn9MNVxDSVJBg==" Jan 11 20:37:18 volumio go-librespot[20965]: time="2025-01-11T20:37:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 20:37:19 volumio go-librespot[20965]: time="2025-01-11T20:37:19+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:19 volumio sudo[20977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 20:37:19 volumio sudo[20977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:37:19 volumio sudo[20977]: pam_unix(sudo:session): session closed for user root Jan 11 20:37:19 volumio sudo[20980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 20:37:19 volumio sudo[20980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:37:19 volumio sudo[20980]: pam_unix(sudo:session): session closed for user root Jan 11 20:37:19 volumio volumio[818]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.22 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.201 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 20:37:19 volumio go-librespot[20965]: time="2025-01-11T20:37:19+01:00" level=debug msg="completed challenge" Jan 11 20:37:19 volumio go-librespot[20965]: time="2025-01-11T20:37:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:37:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:19 volumio sudo[20985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 20:37:19 volumio sudo[20985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:37:19 volumio sudo[20985]: pam_unix(sudo:session): session closed for user root Jan 11 20:37:19 volumio sudo[20988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 20:37:19 volumio sudo[20988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:37:19 volumio sudo[20988]: pam_unix(sudo:session): session closed for user root Jan 11 20:37:19 volumio volumio[818]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.22 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.201 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 20:37:20 volumio volumio[818]: info: Received Get System Info Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:37:20 volumio volumio[818]: info: Discovery: Getting this device information Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:20 volumio volumio[818]: info: Listing playlists Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 20:37:20 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 20:37:20 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:20 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 20:37:21 volumio volumio[818]: info: Received Get System Info Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:37:21 volumio volumio[818]: info: Discovery: Getting this device information Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:37:21 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 20:37:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 731. Jan 11 20:37:22 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:22 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:22 volumio go-librespot[20992]: Librespot-go daemon starting... Jan 11 20:37:22 volumio go-librespot[20992]: time="2025-01-11T20:37:22+01:00" level=info msg="generated new device id: af311b394bdfc8efc74b5bb20e1332a5bb13e635" Jan 11 20:37:22 volumio go-librespot[20992]: time="2025-01-11T20:37:22+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:22 volumio go-librespot[20992]: time="2025-01-11T20:37:22+01:00" level=debug msg="obtained new client token: AADewe2dhXot81F4bI73UP3B3F0LbBKVKYIURuCbmNco0ihCXM7D82LxAneYoqJeUlMv4ROGZEFUyaD30x7Kb7AWetiTL5EMdsCfVZXR+8HuYzhq2jZ/vFyqI1I1fQxphccEfzQQOxllLtcPlHYD9ZRQJhsZA5SqyvL2vsXHfvJHUjuC137N7HvXT77Fhp84bGBdVFayKBr3P1MYWb236Oo4SQoOonPWApS2Bi+LQJ3EuSBA/DKovw05V3wyxg==" Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: info: Retrieving Cloud Streaming UI Jan 11 20:37:22 volumio volumio[818]: info: Getting Tidal Cloud Configuration Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: info: Getting Qobuz Cloud Configuration Jan 11 20:37:22 volumio volumio[818]: info: Asking plugin for UI Config Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: info: Getting Spotify Cloud Configuration Jan 11 20:37:22 volumio volumio[818]: info: Asking plugin for UI Config Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: info: Saving Spotify Acccount Jan 11 20:37:22 volumio volumio[818]: info: Got Tidal Cloud Configuration Jan 11 20:37:22 volumio volumio[818]: info: Got it Jan 11 20:37:22 volumio volumio[818]: info: Got it Jan 11 20:37:22 volumio volumio[818]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 20:37:22 volumio volumio[818]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 20:37:22 volumio go-librespot[20992]: time="2025-01-11T20:37:22+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 20:37:22 volumio volumio[818]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 20:37:23 volumio go-librespot[20992]: time="2025-01-11T20:37:23+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 20:37:23 volumio volumio[818]: info: Received Get System Info Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 20:37:23 volumio volumio[818]: info: Discovery: Getting this device information Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:23 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 20:37:23 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:23 volumio go-librespot[20992]: time="2025-01-11T20:37:23+01:00" level=debug msg="new websocket client" Jan 11 20:37:23 volumio volumio[818]: info: Connection to go-librespot Websocket established Jan 11 20:37:23 volumio go-librespot[20992]: time="2025-01-11T20:37:23+01:00" level=debug msg="completed challenge" Jan 11 20:37:23 volumio go-librespot[20992]: time="2025-01-11T20:37:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 11 20:37:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 20:37:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 20:37:23 volumio volumio[818]: info: Connection to go-librespot Websocket closed Jan 11 20:37:25 volumio volumio[818]: info: Disabling MyMusic plugin upnp Jan 11 20:37:25 volumio sudo[21001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 11 20:37:25 volumio sudo[21001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:37:25 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jan 11 20:37:25 volumio volumio[818]: error: Upnp client error: Error: This socket has been ended by the other party Jan 11 20:37:26 volumio volumio[818]: info: Getting Spotify volume Jan 11 20:37:26 volumio volumio[818]: (node:818) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:26 volumio volumio[818]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Jan 11 20:37:26 volumio volumio[818]: (node:818) 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: 102) Jan 11 20:37:26 volumio volumio[818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 11 20:37:26 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Jan 11 20:37:26 volumio volumio[818]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Jan 11 20:37:26 volumio volumio[818]: info: Initializing connection to go-librespot Websocket Jan 11 20:37:26 volumio volumio[818]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 20:37:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 20:37:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 732. Jan 11 20:37:26 volumio systemd[1]: Stopped go-librespot Daemon. Jan 11 20:37:26 volumio systemd[1]: Started go-librespot Daemon. Jan 11 20:37:26 volumio go-librespot[21003]: Librespot-go daemon starting... Jan 11 20:37:26 volumio go-librespot[21003]: time="2025-01-11T20:37:26+01:00" level=info msg="generated new device id: 00d08be49a38700ee89189026545948e18a6eabe" Jan 11 20:37:26 volumio go-librespot[21003]: time="2025-01-11T20:37:26+01:00" level=debug msg="stored credentials found for sloam8" Jan 11 20:37:26 volumio go-librespot[21003]: time="2025-01-11T20:37:26+01:00" level=debug msg="obtained new client token: AADpD2AehF0OXQyS3DLJDijQX6JAkWjBm1waLcXh4GAY43n2Ad0MR/uzIxkt6f8WaJ3ioCyiMn+rDUuN2HWpbx6GuP9lPwFWvd1cg3b1mE1Wo2mG13wBHDaUPFqJCvQ2ufTRN+/4smbPYCAU22llyH7dAPA4atR32obCq8h+3HzHlLcVsQ06ynNh8MJKfDrdra78JvHYuLRfP6HYIfj0xYpws4jjmUb3JjVKbsV04sFX+aR2Pi7w/t0R8KLp2Q==" Jan 11 20:37:26 volumio go-librespot[21003]: time="2025-01-11T20:37:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 20:37:27 volumio go-librespot[21003]: time="2025-01-11T20:37:27+01:00" level=debug msg="completed keyexchange" Jan 11 20:37:27 volumio volumio[818]: info: Enabling MyMusic plugin upnp Jan 11 20:37:27 volumio volumio[818]: info: Enabling plugin upnp Jan 11 20:37:27 volumio volumio[818]: info: Loading plugin "upnp"... Jan 11 20:37:27 volumio volumio[818]: info: [1736624247112] Starting Upmpd Daemon Jan 11 20:37:27 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 20:37:27 volumio volumio[818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:37:27 volumio volumio[818]: Error: listen EADDRINUSE: address already in use :::6599 Jan 11 20:37:27 volumio volumio[818]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jan 11 20:37:27 volumio volumio[818]: at listenInCluster (net.js:1379:12) Jan 11 20:37:27 volumio volumio[818]: at Server.listen (net.js:1465:7) Jan 11 20:37:27 volumio volumio[818]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 11 20:37:27 volumio volumio[818]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 11 20:37:27 volumio volumio[818]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 11 20:37:27 volumio volumio[818]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 11 20:37:27 volumio volumio[818]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jan 11 20:37:27 volumio volumio[818]: code: 'EADDRINUSE', Jan 11 20:37:27 volumio volumio[818]: errno: -98, Jan 11 20:37:27 volumio volumio[818]: syscall: 'listen', Jan 11 20:37:27 volumio volumio[818]: address: '::', Jan 11 20:37:27 volumio volumio[818]: port: 6599 Jan 11 20:37:27 volumio volumio[818]: } Jan 11 20:37:27 volumio volumio[818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:37:27 volumio sudo[21020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 20:36 Jan 11 20:37:27 volumio sudo[21020]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"