-- Logs begin at Mon 2025-02-24 15:46:49 CET, end at Mon 2025-03-03 19:25:28 CET. -- Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+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]" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="zeroconf server listening on port 37205" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="obtained new client token: AADtNx4kxzlXqKJvNCReNnKp4YNu6m1LDcEczAFbztZjfCnxIG79Yp4ar8AFVXXQZhCfL5p/hQZSf4agycZr3y/JX1mUFAzv/vFSuecTG6KyUWmyQ+pU+CzJC/dwNBcAIP4eOYSrw+/k45vO6xshimP5JixyeA54b8aVcHNjt2GYeJfACaR4T9fYSLgE/buZE1yaDUukr7s450LO5MY+OzlXz4II8hXiA83uhHad9DGQsT6gzlbpZ2L2DllJxg==" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:00 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:00+01:00" level=debug msg="completed challenge" Mar 03 19:24:01 minidsp-shd go-librespot[11806]: time="2025-03-03T19:24:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:02 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 19:24:02 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 19:24:02 minidsp-shd volumio[621]: info: Discovery: Getting this device information Mar 03 19:24:02 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:02 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 19:24:02 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:02 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 720. Mar 03 19:24:04 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:04 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:04 minidsp-shd go-librespot[11823]: Librespot-go daemon starting... Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=info msg="generated new device id: 6afc2a3c845b8cb0532af05a4f3e6979f7881c02" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+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]" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="zeroconf server listening on port 36817" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="obtained new client token: AABSFhF+qepGprV11hQ2USA9UmNgWJGmH+U0C/nbobbD2uPf+oOFesgFWWMX2wHzc8N95xQ7c1ZvXaiWfQFdAlU8XlDoGrSO6bTIFT1qWdbp/beNDNlPpGfOCD7GzUnd1QZY3kJQchtTEdHQQk1OBrnoRbaB1NBJ4m+7Q2hm3A933toveW1GjRu+UPJgiUKLso7OHTLuOA1nohqAwvk3Q5JVC36iwAPuDNAE4XrNy00DL+hrcDudcPJQsgIYoA==" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:04 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:04+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:05 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:05+01:00" level=debug msg="completed challenge" Mar 03 19:24:05 minidsp-shd go-librespot[11823]: time="2025-03-03T19:24:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:05 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:05 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 721. Mar 03 19:24:08 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:08 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:08 minidsp-shd go-librespot[11839]: Librespot-go daemon starting... Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=info msg="generated new device id: a55c4e8bfc37d7bde5a443eba52b3a260ca18dfa" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+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]" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="zeroconf server listening on port 38519" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="obtained new client token: AACs0P/AZcG544WQeewDfvnY0wKP2kN992x98QEUw2ZKgRKL1qH87/Rj2afvi0+QxkgKgTOM13yyeQIvykSHwUaHcIVHHo+cbpGYJD1MdBGhcOPm+LoYDPZG++rfzG59RKCJIRwjQUx+k7vIMtnlCRtAsyPdtSGfCZp/uKDXlItj+yJPT965HmZbOuiSOm6+u+Xd7DHJ544wAwoa6hKDW8zpSIYGniszo6Q+Rj85wPrB53n0Du2kPtOiK4UDvg==" Mar 03 19:24:08 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="new websocket client" Mar 03 19:24:08 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:08 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:08+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:09 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:09+01:00" level=debug msg="completed challenge" Mar 03 19:24:09 minidsp-shd go-librespot[11839]: time="2025-03-03T19:24:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:09 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:24:11 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:24:11 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:11 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:24:11 minidsp-shd volumio[621]: (node:621) 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: 242) Mar 03 19:24:11 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 03 19:24:11 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:11 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:24:12 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:12 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 722. Mar 03 19:24:12 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:12 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:12 minidsp-shd go-librespot[11858]: Librespot-go daemon starting... Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+01:00" level=info msg="generated new device id: 87a80d047db7df0601850a1233e9bd598d78b8dd" Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+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]" Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:12 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:12+01:00" level=debug msg="zeroconf server listening on port 33601" Mar 03 19:24:13 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:13+01:00" level=debug msg="obtained new client token: AADPlntWHSf0LhSNfcHKU0ARA1zIkklhsWwRHzPrxx+0Z9KpXMu+bidK98bYWsW4qp+PSAWPOBv06wNHfnoBZyBF6cPkHr2Z2ND+l8MZoOSliEeym77aQQzasY23TVb4lw3kSpUF9G/fLJ+hs9Fse/Q2J/nGT/+bkGFfuku3QiVvUlaKa+Df2f7RUwgPphz1EtbIchm5qZnLbmwV6+G7fSDtOR/Zsn8ynehFxjNJOhss5l8jgZ27dGDtSw0=" Mar 03 19:24:13 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:13+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:13 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:13+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:13 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:13+01:00" level=debug msg="completed challenge" Mar 03 19:24:13 minidsp-shd go-librespot[11858]: time="2025-03-03T19:24:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:15 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:15 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 723. Mar 03 19:24:16 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:16 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:16 minidsp-shd go-librespot[11875]: Librespot-go daemon starting... Mar 03 19:24:16 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:16+01:00" level=info msg="generated new device id: 175d839746b96a0caa10d971790858d34b1f65fb" Mar 03 19:24:16 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:16+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="zeroconf server listening on port 39501" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="obtained new client token: AAB1X1ZqPDNdZ/2rE223i18P4WH07CDw1ojwBrk+cu0a+nPl0932CU0M020fmkDtCN+ztAcvEd+0tWeqyZzCyYHJEDTjsO2fAJNNdh9XgP9OHUQcDBVNNn+c0L1Bz0yd+PzImE/K+zS2RAOIoI0XL/qgIueHEQgjn3QjnU7ZpKuUXuhzU25cFcDkLix37xL4OTCzfkaHFEb8tkqEFoCYRRGUe0L2eOzhHoTqpWcvl9xnW24/0/Sxq+8ISAL07A==" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:17 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:17+01:00" level=debug msg="completed challenge" Mar 03 19:24:18 minidsp-shd go-librespot[11875]: time="2025-03-03T19:24:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:18 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:18 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 724. Mar 03 19:24:21 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:21 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:21 minidsp-shd go-librespot[11892]: Librespot-go daemon starting... Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=info msg="generated new device id: 6326ce4b1e669375ee5e1ef0dc153fb27391dc87" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+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]" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="zeroconf server listening on port 33927" Mar 03 19:24:21 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="new websocket client" Mar 03 19:24:21 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="obtained new client token: AAA7GE+Otak3gbe12nWbKDFKMpm0iYShPJ9i+SheRKrFs674ykkYTWg15GQ3Dh8OqqwXcy9ox3J/QxNBDtznWWgL/lSdmFikI+oOSuIXfRO8ZADcfyGEED5P1C23y/qykDa4Ny3o1ncYkiXNcCT+tt5GuGrBY1d9xe7trCfJDBVD1Ct8Fq/9xBkXNXsmyZ4u835YiFTzopXSnLhAYj+e0kxxRySdp8zAgt9/CTYbqf90xeQ1prqdXitC0nBfWg==" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:21 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:21+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:22 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:22+01:00" level=debug msg="completed challenge" Mar 03 19:24:22 minidsp-shd go-librespot[11892]: time="2025-03-03T19:24:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:22 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:24:24 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:24:24 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:24 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:24:24 minidsp-shd volumio[621]: (node:621) 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: 243) Mar 03 19:24:24 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 03 19:24:24 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:24 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:24:25 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:25 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 725. Mar 03 19:24:25 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:25 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:25 minidsp-shd go-librespot[11908]: Librespot-go daemon starting... Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=info msg="generated new device id: 16d1f729fc60b0cffc318772e55e7ebb7aa825de" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+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]" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="zeroconf server listening on port 41559" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="obtained new client token: AAAC2/bIQzyyKBKBrneMmEMlHeF80dHRluWwuqnirmn4rKIuQfJQ68eDEQZVSHZ2uZr0R/qycs62RnBLT5YvZ1o8Oi77rLVfcrHQHD3TYNq7nxuIr5mZL8WoBDvD27mq6oYUGa4Y7LlvTTnMUG4eluSOk3qYVKxeFg9pAbdjo77wGQYuZIeqrZE+h6BDzNWULzGHxbK3lIRhzHtc16X/DTsaE+2Z+ZLf9hd+aieQmylh0YWCJEBt0bOICdnusQ==" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:25 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:25+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:26 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:26+01:00" level=debug msg="completed challenge" Mar 03 19:24:26 minidsp-shd go-librespot[11908]: time="2025-03-03T19:24:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:28 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:28 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:29 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:29 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 726. Mar 03 19:24:29 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:29 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:29 minidsp-shd go-librespot[11925]: Librespot-go daemon starting... Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=info msg="generated new device id: 2bce3962da6434786a8a85d673caa9474344c48b" Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:29 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:29+01:00" level=debug msg="zeroconf server listening on port 43017" Mar 03 19:24:30 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:30+01:00" level=debug msg="obtained new client token: AACoekdYvCfA6vK/hHwawTmi/7bPaqkfvI/a03Oaqelkaj+VlNOeoCvkgTBCgiiJsjY2O2Q3N/Vflejykeafr4HnrKRm/RIgeLj1Nz1JngaR66kzjMwUyUlNwLMs+Z23O5IhKfehSGSIs5oDxSwsPBM5ZvZYrT7INNbUAfKM/6aLrgPR0/FZ3jrhB+/VnntDn+NHeSRhFJJlAshi/XYCG8VMQ/6Wz9INEcxGia/WL6XSCpas6IVVDPB30HI=" Mar 03 19:24:30 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:30+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:30 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:30+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:30 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:30+01:00" level=debug msg="completed challenge" Mar 03 19:24:30 minidsp-shd go-librespot[11925]: time="2025-03-03T19:24:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:30 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:31 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:31 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:32 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 19:24:32 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 19:24:32 minidsp-shd volumio[621]: info: Discovery: Getting this device information Mar 03 19:24:32 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:32 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 19:24:33 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:33 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 727. Mar 03 19:24:33 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:33 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:33 minidsp-shd go-librespot[11942]: Librespot-go daemon starting... Mar 03 19:24:33 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:33+01:00" level=info msg="generated new device id: 38fdb5f7f20b8e6f5a1b78578234e8c1b94fb4fa" Mar 03 19:24:33 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:33+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="zeroconf server listening on port 36127" Mar 03 19:24:34 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="new websocket client" Mar 03 19:24:34 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="obtained new client token: AAD1LqOHOArFKR1inJm94TS1Z2FNHsyOdCjRzNFyMF8d80+Byojf1axvmk8+9MqFQcRvA5fwA+BAenso6YPhKArpD+l8id/3nmIwSujwBMZJ59EREyWPSdiifW13yqGocdkKFpWAoj7b/JkA2DcaBHnLcE2Q++mj9ai4UkoFShaGGOYB2crdIiZzllfr0tk2j2gFQ5p95vKL9t4wrqdJt1+RxZwyXlg2z9hcFMrDf3qP5V5cfz77ez0/Uc/cyQ==" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:34 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:34+01:00" level=debug msg="completed challenge" Mar 03 19:24:35 minidsp-shd go-librespot[11942]: time="2025-03-03T19:24:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:35 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:35 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:35 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:24:37 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:24:37 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:37 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:24:37 minidsp-shd volumio[621]: (node:621) 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: 244) Mar 03 19:24:37 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 03 19:24:37 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:37 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:24:38 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:38 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 728. Mar 03 19:24:38 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:38 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:38 minidsp-shd go-librespot[11959]: Librespot-go daemon starting... Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=info msg="generated new device id: 7b3d8db47c6dedbe17ab99fb7c3e234e10c26e61" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="zeroconf server listening on port 42685" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="obtained new client token: AAAz4p5qYRL/oe7gycV9/EvoNIoPoPWeFMqJY7MUP62DfQiFle5PTXujBvIZagypNLZLD9FKNSFyEBL59Sm4VGrW44VO8Dbw9W6XhXOZZ3th1UC6gcFXOaeEtjhoIUOit39P6A5N+XOKKIZwOdgHM9km3iKJz3SKl/VLZXOZeay6WQKzXwaBEdpnHoTFFFUFsfnd2F8RCMbuGYm+dt2cBTX5z5OEs9tJ5corxbNaqBOr55rnN2H+Qn1HCq+jAQ==" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:38 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:38+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:39 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24:39+01:00" level=debug msg="completed challenge" Mar 03 19:24:39 minidsp-shd go-librespot[11959]: time="2025-03-03T19:24: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" Mar 03 19:24:39 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:39 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:41 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:41 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:42 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:42 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 729. Mar 03 19:24:42 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:42 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:42 minidsp-shd go-librespot[11976]: Librespot-go daemon starting... Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=info msg="generated new device id: fdbb4b617486a9a77c711fad91777b033bc38a98" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+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]" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="zeroconf server listening on port 40421" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="obtained new client token: AAD8l1haY2WmhwuKZwqj5287EIp4ZkJltmde0aQXALJ+WT1virEwp77LoVDkOqu9o47XfOMQPUaK8+yLR3rzqbu4xDk0PkzWucnCWscNJpeBYFCiJK7owebDIbopwIKARrHlHmHfVqJLB0Sjm5YPxx1WpNzZd4kfHT5tZx8UYeGdBXnWyG7N3phIFa5xdIPkt+hyhPn+vNCpbGYi8STvKd8SuvQOBwQKuLDIqs8JjUcyrwlrYyRuCjV8xIMm7A==" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:42 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:42+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:43 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24:43+01:00" level=debug msg="completed challenge" Mar 03 19:24:43 minidsp-shd go-librespot[11976]: time="2025-03-03T19:24: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" Mar 03 19:24:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:44 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:44 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 730. Mar 03 19:24:46 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:46 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:46 minidsp-shd go-librespot[11994]: Librespot-go daemon starting... Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+01:00" level=info msg="generated new device id: 740767f32aef9cacd32503dda1634af9f4c1dd71" Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+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]" Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:46 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:46+01:00" level=debug msg="zeroconf server listening on port 45135" Mar 03 19:24:47 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:47+01:00" level=debug msg="new websocket client" Mar 03 19:24:47 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:47+01:00" level=debug msg="obtained new client token: AABnuHuM5xt2rhguXR99CPWCNZil20osdi9/6Pc+gjPbFa1bJ38y8cPCh9Uj112Y0Woy1d77zl8nyhLn4Q9MZrMhEmp3rYCbnMoyYi4J/0IkjozP/8Wxo2X3MsWsne/86omfNY/TvjgGR1HkVz/OR6tZhtYNsu7Dr1EbdPAMSB1OzC9hwxRVDdAKPDm/Yam2Nf3FmtO72BLqceSmTW/3da3Sx5+Bq+ZN82UUBrKPQTKvFy3Vcm99N0r40/U=" Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:47+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:47+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24:47+01:00" level=debug msg="completed challenge" Mar 03 19:24:47 minidsp-shd go-librespot[11994]: time="2025-03-03T19:24: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" Mar 03 19:24:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:47 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:24:50 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:24:50 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:50 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:24:50 minidsp-shd volumio[621]: (node:621) 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: 245) Mar 03 19:24:50 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 03 19:24:50 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:24:50 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:24:50 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:50 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:50 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:50 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 731. Mar 03 19:24:50 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:50 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:50 minidsp-shd go-librespot[12010]: Librespot-go daemon starting... Mar 03 19:24:50 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:50+01:00" level=info msg="generated new device id: 0e7d1aecb1267c6a84222c11431d7e12262e744a" Mar 03 19:24:50 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:50+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+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]" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="zeroconf server listening on port 46077" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="obtained new client token: AACstdvxsTKnxBD/bu64B14Ml215kDU8IL+jiPwkhY5Q3fxH4eWJk5UXDKN72nIyInwqfxHJI/bG73ZU/XDhQ5H/rcoU5kRPL5vIsJc8L9EgHl3RgoMnJov5KlkVV9kFcuWlK0lqDeRz9hLOx5kxTe1i4vy8sW06Cc7NL1iuIa37yv0D0ZH5YsNwCno5c+jVmLkxyZVXX5CkDq8ZxeHyuElww5vgAwZvAIWEJdooJynadzpun6uzE8y6LpBLHg==" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:51 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:51+01:00" level=debug msg="completed challenge" Mar 03 19:24:52 minidsp-shd go-librespot[12010]: time="2025-03-03T19:24:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:52 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:52 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:53 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:53 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:55 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:55 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 732. Mar 03 19:24:55 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:55 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:55 minidsp-shd go-librespot[12026]: Librespot-go daemon starting... Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=info msg="generated new device id: 03f18527ca839a6cb18a40085cc9f372136e107e" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="zeroconf server listening on port 39127" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="obtained new client token: AAC5Rk9ZADQDaiNfIIC4SiHJSZP1Pei1+TK8Y9kY0tzbUUU0gIwbKQdsNAl7I4Qt0XTP7w8+uksWBH/DBJGNvYrd2OOLp1+ZOlt0P/LG2YrjN2MIbfjluwjHSfi67CnYjevXx2iwCCmOpmPCmiUgZ/RneMNrndXv+i8gRENVdqpNYJN6d0TnzifopomuhujaTxK1TV0v6gQylYU0v2GVD16FSvvDMtHdv0f3iqxrgwqS8mYqr8WNgeMJtfnD+w==" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:55 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:55+01:00" level=debug msg="completed keyexchange" Mar 03 19:24:56 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:56+01:00" level=debug msg="completed challenge" Mar 03 19:24:56 minidsp-shd go-librespot[12026]: time="2025-03-03T19:24:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:24:56 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:24:56 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:24:56 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:56 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:24:59 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:24:59 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 733. Mar 03 19:24:59 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:24:59 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:24:59 minidsp-shd go-librespot[12080]: Librespot-go daemon starting... Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=info msg="generated new device id: 8c379e57de6cac80eb5c33ad94911478221233a5" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+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]" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="zeroconf server listening on port 35531" Mar 03 19:24:59 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="new websocket client" Mar 03 19:24:59 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="obtained new client token: AADEVid2j3Vdf+ChT1vJBGGy+biSop071XsWKYI3+uEIcZ1GLf0dFA9uSuGw+gyPsR9X8MqiLMPNUlH83DeV0jem6bCT0bDl6MPs5WBzmmjpUQhwNcaJBDeG4CnUP23gUPsmaHpIgX2vrjRVjTbOc/KROEHnKbdIEk9ZlDukMY6/U4eK9WwIusiXX4O/+xtkWOJQlLJxk26L+thk4b7w2uEMJKTaPqXvsivYuiXwuoaDQBw+jenDbmfDDVz2Fg==" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:24:59 minidsp-shd go-librespot[12080]: time="2025-03-03T19:24:59+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:00 minidsp-shd go-librespot[12080]: time="2025-03-03T19:25:00+01:00" level=debug msg="completed challenge" Mar 03 19:25:00 minidsp-shd go-librespot[12080]: time="2025-03-03T19:25:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:00 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:25:02 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:25:02 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:02 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:25:02 minidsp-shd volumio[621]: (node:621) 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: 246) Mar 03 19:25:02 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Mar 03 19:25:02 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:02 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:25:03 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:03 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 734. Mar 03 19:25:03 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:03 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:03 minidsp-shd go-librespot[12096]: Librespot-go daemon starting... Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+01:00" level=info msg="generated new device id: bbaa89d3ec4c61da6a54099fde7ea9e1870738ae" Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+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]" Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:25:03 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:03+01:00" level=debug msg="zeroconf server listening on port 33431" Mar 03 19:25:04 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:04+01:00" level=debug msg="obtained new client token: AADLPYBdD9aLlRFbMNaEcXjnd7vI6ohGNOkksc8565kXJ0pVWAB3sw8zhl8FcfkhoNUBt+RWBjcjFMkt3XZ0eMv+pptNX7sJyADWqAuxCNVvJydecoXyh6P1VD0rPDf8TZkytE5P+bAJEQtLHfBMDn9gXCJxnymSJvVA7Hm1erGRPK3NkTx0Wph7u//AwLG9TA1LvdjBtrr5b9Af6SSSW6uQWfbAx7tM3l4NYuIUa1iQVxjpLTmxGhdds/E=" Mar 03 19:25:04 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:04+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:04 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:04+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:04 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:04+01:00" level=debug msg="completed challenge" Mar 03 19:25:04 minidsp-shd go-librespot[12096]: time="2025-03-03T19:25:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:06 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:06 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 735. Mar 03 19:25:07 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:07 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:07 minidsp-shd go-librespot[12112]: Librespot-go daemon starting... Mar 03 19:25:07 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:07+01:00" level=info msg="generated new device id: 9ac04e5a82916feb0529b7c49348b802d1ee51d0" Mar 03 19:25:07 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:07+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+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]" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="zeroconf server listening on port 37705" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="obtained new client token: AAAxElNocZUchkLKHlNyiQX7tWVv060OaCodCuKWtUI0skZLiFKBVBCwk7TOrFLLJ+PciaPaBkrVAsbgcdNFC51knoxbLuNxvtwo5Fss3yoEDRxB7Ib2J31b+95SfKC64gfKlyQBp1ZJ1DkxKte+PKkdFp5s9gV/Zv6+rp9c/Mo7qUPo3UClOJnwcQtqVR/OjjpOzjumEqBOJ89HOjK72pCawFvfWILGdNeFi6gR5Vgh9wKQGdsMQALMxIEAyA==" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=debug msg="completed challenge" Mar 03 19:25:08 minidsp-shd go-librespot[12112]: time="2025-03-03T19:25:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:09 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:09 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:09 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 736. Mar 03 19:25:12 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:12 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:12 minidsp-shd go-librespot[12130]: Librespot-go daemon starting... Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=info msg="generated new device id: fbdfe012858aba9eb16585eae51d01dcca067b98" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="zeroconf server listening on port 45539" Mar 03 19:25:12 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="new websocket client" Mar 03 19:25:12 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="obtained new client token: AAAyNWztPFr218S3b1ejmunP3SGrRUsanrAS/OyUjFIsaDhF+uFzEV2YJhL6Dv0RYzm/Kzr/OEaNayTyEPOmZDSoDvNbYdjs/7eZGr9W8kiS3Em8OX2llIvDiH4rkWSElBCKvm6BEEuTj04qntMbOgm6waRym/nAjhTM0scnpLHddfgA1hGA5XI0GH6bJItt/s26XEPmWzwewbSroYHuMsaubGQmfh0J7yTek1PgHq8U76zYuvQ+sz2OKL2AJw==" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:12 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:12+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:13 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:13+01:00" level=debug msg="completed challenge" Mar 03 19:25:13 minidsp-shd go-librespot[12130]: time="2025-03-03T19:25:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:13 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 19:25:14 minidsp-shd volumio[621]: info: Discovery: Getting this device information Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 19:25:14 minidsp-shd volumio[621]: info: Discovery: Getting this device information Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:14 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 19:25:14 minidsp-shd volumio[621]: verbose: New Socket.io Connection to 192.168.1.27:3000 from 192.168.1.217 UA: Dart/3.5 (dart:io) Total Clients: 7 Mar 03 19:25:15 minidsp-shd volumio[621]: info: Getting Spotify volume Mar 03 19:25:15 minidsp-shd volumio[621]: (node:621) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:15 minidsp-shd volumio[621]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 03 19:25:15 minidsp-shd volumio[621]: (node:621) 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: 247) Mar 03 19:25:15 minidsp-shd volumio[621]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Mar 03 19:25:15 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:15 minidsp-shd volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 19:25:16 minidsp-shd volumio[621]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.217 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 19:25:16 minidsp-shd volumio[621]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Mar 03 19:25:16 minidsp-shd volumio[621]: info: Received Get System Info Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 19:25:16 minidsp-shd volumio[621]: info: Discovery: Getting this device information Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetState Mar 03 19:25:16 minidsp-shd volumio[621]: info: Listing playlists Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::volumioGetQueue Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreStateMachine::getQueue Mar 03 19:25:16 minidsp-shd volumio[621]: info: CorePlayQueue::getQueue Mar 03 19:25:16 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:16 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 737. Mar 03 19:25:16 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:16 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:16 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 19:25:16 minidsp-shd go-librespot[12146]: Librespot-go daemon starting... Mar 03 19:25:16 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=info msg="generated new device id: ca340b888e0ec346516063d21f1ddebf9140e2e9" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="zeroconf server listening on port 36663" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="obtained new client token: AAC1/PVEC9V9jrhZXmm7Cfw6/ubKFi2jmvGMI6H/uSHl0avRdZJ650ohl7X5ebaQ3XVsErlujFG7zhcDyLEaWUAUiV1aLMgdyFWPH1+ny3epKvtPhjKr2EVdsIZIGAV3Deg7K/aiwXbGHxetCt8IxYWL3D51vahivPJXGlSJSdSVxcuqaHylhOF9hMdMJUzAJgoHoRvJWmfFSc+VLpj1BK5RG1UVapTc8X6nxpcchK3sOW1vUl/jwmKX1GAJ3Q==" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:16 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:16+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:17 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:17+01:00" level=debug msg="completed challenge" Mar 03 19:25:17 minidsp-shd go-librespot[12146]: time="2025-03-03T19:25:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:19 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:19 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 738. Mar 03 19:25:20 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:20 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:20 minidsp-shd go-librespot[12164]: Librespot-go daemon starting... Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+01:00" level=info msg="generated new device id: 05c855bb81dab65951449813df9ad0c8fb0cc6fc" Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+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]" Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 19:25:20 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:20+01:00" level=debug msg="zeroconf server listening on port 38023" Mar 03 19:25:21 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:21+01:00" level=debug msg="obtained new client token: AADnE9YAcrLLBZu7TjsFrPUh38U28hyG11DXdyrOKza7i8q9zxd0kQj2Ux/up0Pv+m2Aa2h9lOr/z8FooHrL0k4fXiUTxaN8tpxtrevseVzLMnOEf1kl04hThBZwrKBXPECJlSkasywQuhDeh2E0I5984ohHSi34xcCxlzfRAn6zI5AahJ9WIh3latBSqdwKWcKnyWyGHhN8G0KMypabdQa8rhFumwMsFVzb7hP677C38FyRhmEzZYB/XUI=" Mar 03 19:25:21 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:21+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:21 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:21+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:21 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:21+01:00" level=debug msg="completed challenge" Mar 03 19:25:21 minidsp-shd go-librespot[12164]: time="2025-03-03T19:25:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:22 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:22 minidsp-shd volumio[621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 19:25:24 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 19:25:24 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 739. Mar 03 19:25:24 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 03 19:25:24 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 03 19:25:24 minidsp-shd go-librespot[12180]: Librespot-go daemon starting... Mar 03 19:25:24 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:24+01:00" level=info msg="generated new device id: ecd43cfc75fea617e242dc1c0d43de40dc789a8b" Mar 03 19:25:24 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:24+01:00" level=debug msg="stored credentials found for saschabies80" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+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]" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="zeroconf server listening on port 42343" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="obtained new client token: AABHRj43Qpi29Pjeyu01EzgZHLqIJpmsoVkFUThk3mxPvPlH9E+Bz0/PLTUnDAreGTCqk7u2ww6nO8xqNOuGpD+AGniuvxkJJc51OTxPRImsq+6IBVkvRRxXr5mgjPICGnyMSbaIaZfjhocEGVUIzMyB5u/MtImewdFbsLiuTgBUrw/yPz36aMV4nA4J2R/tFlIF3QDb1ZA2HSmYaZUfY92U5D3xeuFEsLrEsSndZG530FgNYEhdoQM9MIJbbw==" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 03 19:25:25 minidsp-shd volumio[621]: info: Initializing connection to go-librespot Websocket Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="completed keyexchange" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="new websocket client" Mar 03 19:25:25 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket established Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=debug msg="completed challenge" Mar 03 19:25:25 minidsp-shd go-librespot[12180]: time="2025-03-03T19:25:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Mar 03 19:25:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 19:25:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 19:25:25 minidsp-shd volumio[621]: info: Connection to go-librespot Websocket closed Mar 03 19:25:26 minidsp-shd volumio[621]: Searching all installed plugins Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Mar 03 19:25:26 minidsp-shd volumio[621]: info: [1741026326936] ControllerCalmRadio::searchCategories Mar 03 19:25:26 minidsp-shd volumio[621]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 19:25:26 minidsp-shd volumio[621]: TypeError: Cannot read property 'cats' of undefined Mar 03 19:25:26 minidsp-shd volumio[621]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Mar 03 19:25:26 minidsp-shd volumio[621]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Mar 03 19:25:26 minidsp-shd volumio[621]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Mar 03 19:25:26 minidsp-shd volumio[621]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Mar 03 19:25:26 minidsp-shd volumio[621]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Mar 03 19:25:26 minidsp-shd volumio[621]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Mar 03 19:25:26 minidsp-shd volumio[621]: at Socket.emit (events.js:400:28) Mar 03 19:25:26 minidsp-shd volumio[621]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 03 19:25:26 minidsp-shd volumio[621]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 03 19:25:26 minidsp-shd volumio[621]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 19:25:28 minidsp-shd sudo[12201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 19:24 Mar 03 19:25:28 minidsp-shd sudo[12201]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"