-- Logs begin at Tue 2025-03-18 08:44:38 CET, end at Tue 2025-03-18 10:29:42 CET. -- Mar 18 10:28:01 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:01 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1488. Mar 18 10:28:01 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:01 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:01 badezimmer go-librespot[31970]: Librespot-go daemon starting... Mar 18 10:28:01 badezimmer go-librespot[31970]: time="2025-03-18T10:28:01+01:00" level=info msg="generated new device id: 2eb556e8b287015d4390a80c540cbf2cfc22b949" Mar 18 10:28:01 badezimmer go-librespot[31970]: time="2025-03-18T10:28:01+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:02 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=debug msg="new websocket client" Mar 18 10:28:02 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+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 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+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 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=debug msg="zeroconf server listening on port 35401" Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=debug msg="obtained new client token: AABO/niej8GRCb72HPueJ7RKM9X0wcbNwC4C/EGkV7D+JaPk63BSo/BDG52xrfcDI9djxky5wFg+URcqwJm+vb3YzdkQYQIEz7f+eU3S+yQhShw1qoj9aTWZ+jrZs15dLMszD4zkWfqK4IcjmJqsIt+YekZWndTmB3E2sumunTVL1Et5upq4HWnWqMawAuCjgGKUs2Og1StLvxtOAs3GSu+MJlpSNoJg9refzFhnbSirmOOk3Bt+rwYkis+z" Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:02 badezimmer go-librespot[31970]: time="2025-03-18T10:28:02+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:03 badezimmer go-librespot[31970]: time="2025-03-18T10:28:03+01:00" level=debug msg="completed challenge" Mar 18 10:28:03 badezimmer go-librespot[31970]: time="2025-03-18T10:28:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:03 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:03 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:03 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:28:03 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:03 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:05 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:05 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:05 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:28:05 badezimmer volumio[1012]: (node:1012) 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: 494) Mar 18 10:28:05 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 18 10:28:05 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:05 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:06 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:06 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:06 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:06 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1489. Mar 18 10:28:06 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:06 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:06 badezimmer go-librespot[31981]: Librespot-go daemon starting... Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=info msg="generated new device id: f63e706dd533fc9de9e7ba3c3c052fe4b10c5044" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+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 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+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 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=debug msg="zeroconf server listening on port 36695" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=debug msg="obtained new client token: AADHuWZGEIa1OvabzmJylAVt2ij2TN/WmflAgi6O5kmxcE3yimo21HKybI+XxghYdW4k8U15DxS2p9R8VkxhERunRt/lUBSl+j/jWnTNtNRnYPBX/ppVk0fmpZtfvSt1mFbhdAs1PHGzLqIa3DIYzOsetON/2X+e1d7lXBgAgdXou/+A2z5rwPHomDC9pABHP6+/u2qi4ejL/gYyk/gp9t4Pm1FJsKsj67RcIfpBYZDu58FCalh4YjYL2Z1b" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:06 badezimmer go-librespot[31981]: time="2025-03-18T10:28:06+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:07 badezimmer go-librespot[31981]: time="2025-03-18T10:28:07+01:00" level=debug msg="completed challenge" Mar 18 10:28:07 badezimmer go-librespot[31981]: time="2025-03-18T10:28:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:07 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:07 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:07 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:07 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:09 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:09 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:10 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:10 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1490. Mar 18 10:28:10 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:10 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:10 badezimmer go-librespot[32003]: Librespot-go daemon starting... Mar 18 10:28:10 badezimmer go-librespot[32003]: time="2025-03-18T10:28:10+01:00" level=info msg="generated new device id: 976943b0bf6c16619798db1cf2ff5efb11fb8a0d" Mar 18 10:28:10 badezimmer go-librespot[32003]: time="2025-03-18T10:28:10+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:12 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:12 badezimmer go-librespot[32003]: time="2025-03-18T10:28:12+01:00" level=debug msg="new websocket client" Mar 18 10:28:12 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:13 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:13 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:13 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:13 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:15 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:15 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 18 10:28:15 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:15 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+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 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+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 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+01:00" level=debug msg="zeroconf server listening on port 42627" Mar 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+01:00" level=debug msg="obtained new client token: AAD+75otdTJgzYb315F/5gkR4xbXVThNGEf7IMzqT5XdbBRNPP4YEhUFyyadJPFwyCJGYVEGSkItHU3Jjz4qb78wpXYoKyh2Vf1dx4eEA8yHq7qtBPL9y0pIjltQK53Fi6t/MnpN7HMv8xrumc3ek99GBuLLhVa06k9Yf1+OkSwf/DynSQxDAQ7/jIrdXU2gAvV2sgK+GVQ3sPm0Njputtm9/BLXStpMMyEz3f62ITwg4zNa4DYsHdnLkH7/" Mar 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:15 badezimmer go-librespot[32003]: time="2025-03-18T10:28:15+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:15 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:15 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:16 badezimmer go-librespot[32003]: time="2025-03-18T10:28:16+01:00" level=debug msg="completed challenge" Mar 18 10:28:16 badezimmer go-librespot[32003]: time="2025-03-18T10:28:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:16 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:16 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:16 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 18 10:28:16 badezimmer volumio[1012]: at connResetException (internal/errors.js:607:14) Mar 18 10:28:16 badezimmer volumio[1012]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 18 10:28:16 badezimmer volumio[1012]: at Socket.emit (events.js:327:22) Mar 18 10:28:16 badezimmer volumio[1012]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 18 10:28:16 badezimmer volumio[1012]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 18 10:28:16 badezimmer volumio[1012]: (node:1012) 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: 495) Mar 18 10:28:16 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:16 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:16 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:16 badezimmer volumio[1012]: verbose: New Socket.io Connection to 192.168.1.166:3000 from 192.168.1.42 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:16 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 18 10:28:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 18 10:28:19 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:19 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:19 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:19 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1491. Mar 18 10:28:19 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:19 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:19 badezimmer go-librespot[32029]: Librespot-go daemon starting... Mar 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+01:00" level=info msg="generated new device id: bf6d7cbf19336e34e16354ade345a7f772112e51" Mar 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+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 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+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 18 10:28:19 badezimmer go-librespot[32029]: time="2025-03-18T10:28:19+01:00" level=debug msg="zeroconf server listening on port 38607" Mar 18 10:28:20 badezimmer go-librespot[32029]: time="2025-03-18T10:28:20+01:00" level=debug msg="obtained new client token: AAC6Om5SFwbPLEMoKisWUhX50q9e3KwYW3NgYZLh0vzGbjpLc789St5KjnXFFRbmbBmr5c36+2ga1sLZSkfFP3gUrGQWnIBE4unpDVrz7wN5qrGHr1EiYGrSOYvnlCqeoKKLgRyedbtNCHuLmuCmCBmzoe26nIC77QrFCjfq3bz6fV+GtALi0VSekNO6KADtC7oWEaNOtXfFkxmJ1Sop2ODQRl7Duxd465feVOP29tc3qducti6PvfRpyLcO" Mar 18 10:28:20 badezimmer go-librespot[32029]: time="2025-03-18T10:28:20+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:20 badezimmer go-librespot[32029]: time="2025-03-18T10:28:20+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:20 badezimmer sudo[32038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 10:28:20 badezimmer sudo[32038]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 10:28:20 badezimmer sudo[32038]: pam_unix(sudo:session): session closed for user root Mar 18 10:28:21 badezimmer sudo[32041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 10:28:21 badezimmer sudo[32041]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 10:28:21 badezimmer sudo[32041]: pam_unix(sudo:session): session closed for user root Mar 18 10:28:21 badezimmer volumio[1012]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Mar 18 10:28:21 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 18 10:28:21 badezimmer go-librespot[32029]: time="2025-03-18T10:28:21+01:00" level=debug msg="completed challenge" Mar 18 10:28:21 badezimmer go-librespot[32029]: time="2025-03-18T10:28:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:21 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:21 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:21 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 18 10:28:22 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:22 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:22 badezimmer sudo[32045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 10:28:22 badezimmer sudo[32045]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 10:28:22 badezimmer sudo[32045]: pam_unix(sudo:session): session closed for user root Mar 18 10:28:22 badezimmer sudo[32048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 10:28:22 badezimmer sudo[32048]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 10:28:22 badezimmer sudo[32048]: pam_unix(sudo:session): session closed for user root Mar 18 10:28:22 badezimmer volumio[1012]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetVisibleSources Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:22 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 18 10:28:22 badezimmer volumio[1012]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 18 10:28:22 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:22 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:22 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:22 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:22 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 18 10:28:22 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 18 10:28:23 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:23 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:24 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:24 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 18 10:28:24 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:24 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:24 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 18 10:28:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 18 10:28:24 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:24 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1492. Mar 18 10:28:24 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:24 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:24 badezimmer go-librespot[32050]: Librespot-go daemon starting... Mar 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+01:00" level=info msg="generated new device id: c9e5151abf6c54b77edf70c0adc42debb67c789e" Mar 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+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 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+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 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+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 18 10:28:24 badezimmer go-librespot[32050]: time="2025-03-18T10:28:24+01:00" level=debug msg="zeroconf server listening on port 35985" Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=debug msg="obtained new client token: AABVByWfkp1mg9rs51IOYCSYs4qd5Urj9rJC4XMvNammGkhxWbbs9phR01Zi4I8FwzDf++rJ8kv0lDxzlz022ih+7PdF9Ezyl+wsH4VsdjYCjiQRzOIOrTYCovN5a/9qq6gjNNzzpyBk8EJ7qn/CQ98fO8CdF17Z8TxEtaNpgU1h92g0STxKfvj8YOhcZX0bqgu4ZnYTt4PSkUeNoa3nVHyqDLz6wa2zzyuPgufZ6hv/BUh24Bqf0MYzCQ==" Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+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 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:25 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=debug msg="new websocket client" Mar 18 10:28:25 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=debug msg="completed challenge" Mar 18 10:28:25 badezimmer go-librespot[32050]: time="2025-03-18T10:28:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:25 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:25 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:25 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:28:26 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 18 10:28:26 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:26 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:26 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:26 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:26 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:26 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:26 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:27 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 18 10:28:27 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:27 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:27 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:27 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:27 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:27 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:27 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:28 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:28 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:28 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:28:28 badezimmer volumio[1012]: (node:1012) 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: 496) Mar 18 10:28:28 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:28 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 18 10:28:28 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:28 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:28 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: Retrieving Cloud Streaming UI Mar 18 10:28:28 badezimmer volumio[1012]: info: Getting Tidal Cloud Configuration Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: Getting Qobuz Cloud Configuration Mar 18 10:28:28 badezimmer volumio[1012]: info: Asking plugin for UI Config Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: Getting Spotify Cloud Configuration Mar 18 10:28:28 badezimmer volumio[1012]: info: Asking plugin for UI Config Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: Saving Spotify Acccount Mar 18 10:28:28 badezimmer volumio[1012]: info: Got it Mar 18 10:28:28 badezimmer volumio[1012]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 18 10:28:28 badezimmer volumio[1012]: info: Got Tidal Cloud Configuration Mar 18 10:28:28 badezimmer volumio[1012]: info: Got it Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetBrowseSources Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetBrowseSources Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetBrowseSources Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 18 10:28:28 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:28 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:28 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:28 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1493. Mar 18 10:28:28 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:28 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:28 badezimmer go-librespot[32076]: Librespot-go daemon starting... Mar 18 10:28:28 badezimmer go-librespot[32076]: time="2025-03-18T10:28:28+01:00" level=info msg="generated new device id: 5e0b2bbd62a254060d2487b7f126b24355a21574" Mar 18 10:28:28 badezimmer go-librespot[32076]: time="2025-03-18T10:28:28+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28: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 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28: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 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28: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 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=debug msg="zeroconf server listening on port 35789" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=debug msg="obtained new client token: AACpfUHoUgzVilPnW/CdPd1vGY8GWHHAOAj0/pbgwejBqFk5/52AIKZAE1s+KJbY6IolExgWdp78VlkJe8zQzZeAgrSNp+s1wOm5ONXjGRtji0F1PFskHsv/00XjX8FHZwaxkO3U/W/qpJRV9Ynbwavo4MK0OA/Si3mvMNGMRlZzpQm69eBCXasELJXgB6aw+jrMNlv5k2sx7DcSddMlibw8tzDv6HW/wiNfNwsmGledcCuClsJMX/7phw==" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=debug msg="completed challenge" Mar 18 10:28:29 badezimmer go-librespot[32076]: time="2025-03-18T10:28:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:29 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:29 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:31 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:31 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:32 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:32 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:32 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 18 10:28:32 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:33 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:33 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1494. Mar 18 10:28:33 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:33 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:33 badezimmer go-librespot[32084]: Librespot-go daemon starting... Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=info msg="generated new device id: a390f4445b68c84afcf547405d78e77bd555032f" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+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 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+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 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+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 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=debug msg="zeroconf server listening on port 35791" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=debug msg="obtained new client token: AAAkx21rVkqlxDSkRSJm0rxUUwVMFi8z6U/osYmPSD6eeaxNArmiO9JtaCMHycyin82amHySWl9RPIhuriJAW4U2OwAW/ER5WdxDx/BXy9g+fHk5jg58bn8KGFztErsJE4PHNz+DVcCwy2F8inL0ZKXTavbdpWBDOdoGfJ1MRhPWOk/Bu/eiCWkGPg4yTFFl6WuIDJ3jdFUVBkyG4nGZWmO8bBmWvScNS057zG01zlMkcYNOEvU/97MJUz+b" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:33 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:33 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:33 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:33 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=debug msg="completed challenge" Mar 18 10:28:33 badezimmer go-librespot[32084]: time="2025-03-18T10:28:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:33 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:33 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:34 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:34 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:36 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:37 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:37 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1495. Mar 18 10:28:37 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:37 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:37 badezimmer go-librespot[32095]: Librespot-go daemon starting... Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=info msg="generated new device id: 270931a7610f4b2800d9908ca6c4b3776bc298c8" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+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 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+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 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="zeroconf server listening on port 37953" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="obtained new client token: AAD6iGZ4Pvw+gBB1AO6Fco1s4FvInhPgAoCFXFG8HxPb6NNpL2uf27/An5LeBsRDlKDfYpMqkRblahhdlOCjZyyNRurugO9DD9giHthjo1lqriAvH5Lxg1RIYOwqvppKNjXC80R9q6lGPDyUi6AtAuTJTIKtpq/E4f9QG3AmZYGze1wnWXz/3ais1aRRh6ibHZaokR16sNWGM68P1TWCOad26yFsasS0Jc2BETyTqyXG64/kXyPSe1//pI1J" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:37 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="new websocket client" Mar 18 10:28:37 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=debug msg="completed challenge" Mar 18 10:28:37 badezimmer go-librespot[32095]: time="2025-03-18T10:28:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:37 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:37 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:37 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:40 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:40 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:40 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:40 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 18 10:28:40 badezimmer volumio[1012]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 18 10:28:40 badezimmer volumio[1012]: info: Received Get System Version Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 10:28:40 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:40 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:40 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:40 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:40 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:40 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:28:40 badezimmer volumio[1012]: (node:1012) 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: 497) Mar 18 10:28:40 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 18 10:28:40 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:40 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:40 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:40 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:41 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:41 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1496. Mar 18 10:28:41 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:41 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:41 badezimmer go-librespot[32117]: Librespot-go daemon starting... Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=info msg="generated new device id: 43d1952229831cecbc6c987b431dc305b0eedc6b" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+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 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+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 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="zeroconf server listening on port 36813" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="obtained new client token: AADXq1ZkkcOHKu/pgsx0ZSmepJAe4ebnvB38LgBJ/Q7GsLQUDxasHoSiNSgDzkICYqNqy6Vc4mXMptbxlhzCRnGtJFhI9223pjtOJggsLF7xU1TzSFrG9+EQ+biK2m4EusGlEQnqb3bCcUI+UcO6i1L230jFOmz3IiTCox/Wmv7rsptplbYopmHvqE2qNAkMh3TuNmVE2EMtwxUJGfoHIOdGL2JR6ufUw4qn2cPQXOGXaZihn4w0AbDLuZ5u" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=debug msg="completed challenge" Mar 18 10:28:41 badezimmer go-librespot[32117]: time="2025-03-18T10:28:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:41 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:41 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:43 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:43 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:43 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:43 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:44 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:45 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:45 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1497. Mar 18 10:28:45 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:45 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:45 badezimmer go-librespot[32125]: Librespot-go daemon starting... Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=info msg="generated new device id: fd76fea4b26b6671a1e81b3401fcc0d4376f9b6d" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+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 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+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 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=debug msg="zeroconf server listening on port 33985" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=debug msg="obtained new client token: AAAgJi8XHlk1IMBzE8vgQViCP7Q2tkxQv0axwTISNNMnPpqQmLGZMinI0x1MtLwUJITJeEb+K7KW8ncVFM3L0kWEOUhzfh9HMMl4UUp3r2ZHJM3u5UAzpLzNSg9dlTfccTd3I38fmtb01fCB8VErLVfBPXBnNUZr83tnvc522xocHnbpaHQ0WvO2oZ8vuZc90P2OunOp3bJk2Xi3vMNeuuDI3A/1lJuka9tegWAI2Fzd2LcZLMKSYNCUnPfJ" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:45 badezimmer go-librespot[32125]: time="2025-03-18T10:28:45+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:46 badezimmer go-librespot[32125]: time="2025-03-18T10:28:46+01:00" level=debug msg="completed challenge" Mar 18 10:28:46 badezimmer go-librespot[32125]: time="2025-03-18T10:28:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:46 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:46 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:46 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:46 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:48 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:48 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:48 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:28:49 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:49 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1498. Mar 18 10:28:49 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:49 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:49 badezimmer go-librespot[32189]: Librespot-go daemon starting... Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=info msg="generated new device id: 75c21b6d6e4fe3f8c9d51ac579e622b3060fd837" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+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 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+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 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="zeroconf server listening on port 45117" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="obtained new client token: AACjZ3QOnBFfUITLkTbtD35qmF/kYU1YUBa6+C7YmBsm54U9r2cJ8Hu3a+dN3z56g/2SeNeQ38D0rwx1yA/bPocFl6mzwZsyVxGAmO47VarAthvLlXroYqa5m2eRkuGMHhCeXGYclFEoEmpuN20bgrQjiv97veQeV4V9beNgHWBmK7wFSvM/+1bchqVQ26Qq38fNjGo7LBuy6gIwL/hemmMYQLl46f6k27KLOdOJkdwe53H3ZF/TaC/g2kO9" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:49 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:49 badezimmer go-librespot[32189]: time="2025-03-18T10:28:49+01:00" level=debug msg="new websocket client" Mar 18 10:28:49 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:50 badezimmer go-librespot[32189]: time="2025-03-18T10:28:50+01:00" level=debug msg="completed challenge" Mar 18 10:28:50 badezimmer go-librespot[32189]: time="2025-03-18T10:28:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:50 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:28:50 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:50 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:52 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:52 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:52 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:28:52 badezimmer volumio[1012]: (node:1012) 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: 498) Mar 18 10:28:52 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 18 10:28:52 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:52 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:53 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:53 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:28:53 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:28:53 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1499. Mar 18 10:28:53 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:28:53 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:28:53 badezimmer go-librespot[32197]: Librespot-go daemon starting... Mar 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+01:00" level=info msg="generated new device id: 505d55bf2d5b3626925d35732c2f4ae942b1262a" Mar 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+01:00" level=debug msg="stored credentials not found" Mar 18 10:28:53 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:53 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:53 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:53 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+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 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+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 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+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 18 10:28:53 badezimmer go-librespot[32197]: time="2025-03-18T10:28:53+01:00" level=debug msg="zeroconf server listening on port 35939" Mar 18 10:28:56 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:28:56 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:28:56 badezimmer go-librespot[32197]: time="2025-03-18T10:28:56+01:00" level=debug msg="new websocket client" Mar 18 10:28:56 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:56 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:28:57 badezimmer volumio[1012]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetVisibleSources Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:57 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 18 10:28:57 badezimmer volumio[1012]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 18 10:28:57 badezimmer volumio[1012]: info: Received Get System Info Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:28:57 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:57 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:28:57 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:57 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:57 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:28:58 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 18 10:28:58 badezimmer go-librespot[32197]: time="2025-03-18T10:28:58+01:00" level=debug msg="obtained new client token: AACyYOgFFaOW6zNmzKI3sh8JeYet2pyRmwhU2k11oQIkofwPBwuX32UyTYq1SRuCGK6JfAp+FwkYRjmraxQo27hm3jUuPwh4qUkHUsc+XUxQc427eQVLa3JK7vJbXiMsAEtVevsIDpyZYqsaAkwdyTxvyPt2ippNnUBM5+ofoiGkBKq13D4fHa+LKhW1tXjxrOQ9InUtd+ebEal+L9CLmcmMzRTNkLokFiCnT+Kv7oWuRDSu0hHs2WB+V3dN" Mar 18 10:28:58 badezimmer go-librespot[32197]: time="2025-03-18T10:28:58+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:28:58 badezimmer go-librespot[32197]: time="2025-03-18T10:28:58+01:00" level=debug msg="completed keyexchange" Mar 18 10:28:59 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:28:59 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 18 10:28:59 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:28:59 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:28:59 badezimmer go-librespot[32197]: time="2025-03-18T10:28:59+01:00" level=debug msg="completed challenge" Mar 18 10:28:59 badezimmer go-librespot[32197]: time="2025-03-18T10:28:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:28:59 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:28:59 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:28:59 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 18 10:28:59 badezimmer volumio[1012]: at connResetException (internal/errors.js:607:14) Mar 18 10:28:59 badezimmer volumio[1012]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 18 10:28:59 badezimmer volumio[1012]: at Socket.emit (events.js:327:22) Mar 18 10:28:59 badezimmer volumio[1012]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 18 10:28:59 badezimmer volumio[1012]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 18 10:28:59 badezimmer volumio[1012]: (node:1012) 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: 499) Mar 18 10:28:59 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:29:00 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:02 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:02 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:02 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:02 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1500. Mar 18 10:29:02 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:02 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:02 badezimmer go-librespot[32222]: Librespot-go daemon starting... Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=info msg="generated new device id: b2b8793ae62974b3415e6d167054c29d6a95eae0" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+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 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+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 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=debug msg="zeroconf server listening on port 33483" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=debug msg="obtained new client token: AACZJx5YnTlsfVMVrYlU778YnglObr2zgybCjRSz1R1VYhYtcnhA3u368JCX0YquT+Yc1C+TZ6XorPW/vhkUpEzwRosz5E2BFo+12NNznM9dtpcttWiCSi2+NCDAfkWIHOolLq0n22f8wiZTOEaAu4qkbehHo7OEsUzyOINymCfB5WJ80fEhGJnvUR3bw+5xezm9JaiRuxJRwsDpXVRj862RgQyPNPgQTVsaoRK0m7WmAqPPR7YcCcYTuxz2" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:02 badezimmer go-librespot[32222]: time="2025-03-18T10:29:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed solving challenge: failed login: BadCredentials" Mar 18 10:29:02 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:02 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:03 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:03 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:04 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:04 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:04 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:05 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:05 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:06 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:06 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1501. Mar 18 10:29:06 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:06 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:06 badezimmer go-librespot[32231]: Librespot-go daemon starting... Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=info msg="generated new device id: 6630097109478a15d43d281bab22a1b44a35a14c" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+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 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+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 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="zeroconf server listening on port 42091" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="obtained new client token: AADGltIyLDj798vCCjclml9KSV7UNWsOSJ2HuWFfxe9YZ44K6APjLc438+6kizx1AxtnEC/QiVNCVdKvjYViLjboiyqcfQmZ9HDbIRdvYGeUwPl3UtCNJja6FWLAM2cSArh65tDYzK9tK5E6uf9YLZHMwqVH6unzUSQxDiSGi6taTx72xrq3VWxdoq414O7Dww0L/kEZdP2iZOCkWK6g1YHwcuZGlZQndx+iPoTGgA0eNGIZZm4M/lNcL0Hq" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=debug msg="completed challenge" Mar 18 10:29:06 badezimmer go-librespot[32231]: time="2025-03-18T10:29:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:06 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:06 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:08 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:08 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:08 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:09 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:29:09 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:29:09 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:29:09 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:09 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:09 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:29:10 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:10 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1502. Mar 18 10:29:10 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:10 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:10 badezimmer go-librespot[32256]: Librespot-go daemon starting... Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=info msg="generated new device id: 355b06cb591c194cc2a573d30f330b92eed1c47c" Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+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 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+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 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+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 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=debug msg="zeroconf server listening on port 44393" Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=debug msg="obtained new client token: AABHmbf5bkQIo3bcGRMcJ1F7GjjNUSYVAcnpwLspJun+sJ8aTr8qIUyiibzamgp+2BA8W6KliFurRmVoFyTorEDZGGby6KFN/joJFjcr3fGF+tY17mQ3DnfM1U13rdXl1fGjRrdIdJsLa17HzuFjj9I9kSrgih/JQLS2gWXKZHnzyvMd6J02f0dDhdL1B1R3cMvDMmakMNoKl+t7j1xX7dXBo2ndmOYmBqWYVHwDi0KP9VUmW+7PVdCSrKjO" Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:10 badezimmer go-librespot[32256]: time="2025-03-18T10:29:10+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:11 badezimmer go-librespot[32256]: time="2025-03-18T10:29:11+01:00" level=debug msg="completed challenge" Mar 18 10:29:11 badezimmer go-librespot[32256]: time="2025-03-18T10:29:11+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:11 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:11 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:11 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:11 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:12 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:12 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:12 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:13 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:13 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:13 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:29:13 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:29:14 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:14 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1503. Mar 18 10:29:14 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:14 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:14 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:14 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:14 badezimmer go-librespot[32264]: Librespot-go daemon starting... Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=info msg="generated new device id: ff34e7e8aea820b094ccce7caa2f9c4988166aa9" Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+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 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+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 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+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 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=debug msg="zeroconf server listening on port 44279" Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=debug msg="obtained new client token: AADjBti+fQg2QrJvNadRheldKE1uX4mOObt89yjCISmHuULgntDgfbNZzHCklBofb9XOxp+XzDkeAUDkMl2MjYuDUbLzN24L7KQGR9bUBK82XLM490IR7ND88HiLMYOveJwe36s3+5iKo3FFP/1I6U/mDhEqA2ATeMdITPMqU4JlMW0iWnICqrlxDrhwZdEs4xCYIyaw+JE2Bg8TnHyQZFUm0BbVldrmyYNYVnTzT5hKtxE7yfM5AyHbAebm" Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:14 badezimmer go-librespot[32264]: time="2025-03-18T10:29:14+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:15 badezimmer go-librespot[32264]: time="2025-03-18T10:29:15+01:00" level=debug msg="completed challenge" Mar 18 10:29:15 badezimmer go-librespot[32264]: time="2025-03-18T10:29:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:15 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:15 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:16 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:17 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:17 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:18 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:18 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1504. Mar 18 10:29:18 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:18 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:18 badezimmer go-librespot[32289]: Librespot-go daemon starting... Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=info msg="generated new device id: 105acfcf45fd738733eb5b59a371a3c3f2e871be" Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+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 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+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 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+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 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=debug msg="zeroconf server listening on port 42899" Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=debug msg="obtained new client token: AAAfn6MV0gQj0WthNl3y3TiwezFK7m3q6hMgexIQpiVn1MGpu84qemdi0RdHkXIkS8ZkgBHuRXKnLoHPdPEwT40KUzorBXZWbgyr0KtcF/pG/A3B+h9wtSFp7oozW3rhniG2myQ4yCoPFYx+U83PQokiED+9WRpjS/88HsrPOTTLzpz/RY1/T+90yowhctjrchhd64fbvg5GpDa+7KDDtAWqXtgsf5VpekETGxnAjz41/gTsK3y7Id4eiegK" Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+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 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Mar 18 10:29:18 badezimmer go-librespot[32289]: time="2025-03-18T10:29:18+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:19 badezimmer go-librespot[32289]: time="2025-03-18T10:29:19+01:00" level=debug msg="completed challenge" Mar 18 10:29:19 badezimmer go-librespot[32289]: time="2025-03-18T10:29:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:19 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:19 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:20 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:20 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:20 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:21 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:21 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:22 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:22 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1505. Mar 18 10:29:22 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:22 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:22 badezimmer go-librespot[32298]: Librespot-go daemon starting... Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=info msg="generated new device id: 0b351968d06bd0de4eda2ea2b77c2c4a6f9c8873" Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+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 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+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 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+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 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=debug msg="zeroconf server listening on port 42173" Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=debug msg="obtained new client token: AAAYyP42iokcuub34qAT4Zg2vPJt5iFR1qQH4piEQi6csA47Cb9h8GzFhq17l8aP5LzPjFf9g0KPT5IM8vKvXyEtli1s5QSa2Ty0vyKvLLoJ+YXbnHhQhJv12RTe0e7KRGVT3iO4JjEwSP0KOu0ciOc6/b6h34zAUTyKD9pQ1cFxd4PSMxalHAxFyKoFiRYL5qajZNv++xwyWx4tcoowutCYp3W9RsocpMnwmb8cr4FHD8uY6f4WKKIv0nW9" Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:22 badezimmer go-librespot[32298]: time="2025-03-18T10:29:22+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:23 badezimmer go-librespot[32298]: time="2025-03-18T10:29:23+01:00" level=debug msg="completed challenge" Mar 18 10:29:23 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:23 badezimmer go-librespot[32298]: time="2025-03-18T10:29:23+01:00" level=debug msg="new websocket client" Mar 18 10:29:23 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:29:23 badezimmer go-librespot[32298]: time="2025-03-18T10:29:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:23 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:23 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:23 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:29:23 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:23 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:24 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:26 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:29:26 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:26 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:29:26 badezimmer volumio[1012]: (node:1012) 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: 500) Mar 18 10:29:26 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 18 10:29:26 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:26 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:26 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:26 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:26 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:26 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1506. Mar 18 10:29:26 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:26 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:26 badezimmer go-librespot[32311]: Librespot-go daemon starting... Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=info msg="generated new device id: 668678b29a9dd5b478802b51d69698a43518028e" Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+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 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+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 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+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 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=debug msg="zeroconf server listening on port 37911" Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=debug msg="obtained new client token: AADdAFwOe2bCyVoj1G28dmOA2UDgUm+iiIrZ9hfeTUmdfsvAceNxlSMBTv1/+UVaRM8AtxjqeKTEsp/UsJTy5cEZzE9W5lcpYJx0Uz63+KCibrdZ3jvVHSc/SN8p0hBHS6iDCBbV2mPogDhw3v9xVIjqmS5+5wGRf8i/jmVWUbqiJ7hk3Ve5+UAj/LwgsGhd8uaFY4KbAFgokci0UzIT4OZcQFfC2sNlU1b7AtWCqpnh9Xt8z2pDRx5Nf44U" Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:26 badezimmer go-librespot[32311]: time="2025-03-18T10:29:26+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:27 badezimmer go-librespot[32311]: time="2025-03-18T10:29:27+01:00" level=debug msg="completed challenge" Mar 18 10:29:27 badezimmer go-librespot[32311]: time="2025-03-18T10:29:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:27 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:27 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:28 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:29 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:29 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:29 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:29 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:30 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:30 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1507. Mar 18 10:29:30 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:30 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:30 badezimmer go-librespot[32333]: Librespot-go daemon starting... Mar 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+01:00" level=info msg="generated new device id: 53d3a7759cb2a8f79de593bc05879b2e0a6df8cf" Mar 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+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 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+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 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+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 18 10:29:30 badezimmer go-librespot[32333]: time="2025-03-18T10:29:30+01:00" level=debug msg="zeroconf server listening on port 41373" Mar 18 10:29:31 badezimmer go-librespot[32333]: time="2025-03-18T10:29:31+01:00" level=debug msg="obtained new client token: AACxCJVgGwRFS24IS64797USKsXG3ubU+gAZQ0qSVb6zqc1wVrBDx0+GVs6rJc4r7xYl3kX+vyL3JBdy0k8+z26qgzZwnC+zJ38Y46o+qQjtHq1OEFkSFjOKAw5/SeEflwdV/L38LO+4cQFRwFxo1v0RH4OhsoqA4jcTjxGYQPB2qokNyIrwBInxv6JlSkVy9cxCsyhxfbl/ys2vajwydg3+D6KUBz+lyGnNSCatB1kHURRr5xJcL3z26w==" Mar 18 10:29:31 badezimmer go-librespot[32333]: time="2025-03-18T10:29:31+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:31 badezimmer go-librespot[32333]: time="2025-03-18T10:29:31+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:31 badezimmer go-librespot[32333]: time="2025-03-18T10:29:31+01:00" level=debug msg="completed challenge" Mar 18 10:29:31 badezimmer go-librespot[32333]: time="2025-03-18T10:29:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:31 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:31 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:32 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:32 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:32 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:33 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:33 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:33 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:29:33 badezimmer volumio[1012]: info: Listing playlists Mar 18 10:29:34 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:34 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1508. Mar 18 10:29:34 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:34 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:34 badezimmer go-librespot[32341]: Librespot-go daemon starting... Mar 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29:34+01:00" level=info msg="generated new device id: 9297674107e954672f5f661639bebaf1b6ce5026" Mar 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29:34+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29:34+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 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29:34+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 18 10:29:34 badezimmer go-librespot[32341]: time="2025-03-18T10:29:34+01:00" level=debug msg="zeroconf server listening on port 43277" Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=debug msg="obtained new client token: AADGWAnFCTGwhD4ryPoTWIbX8f95VIrwP5982CTaHgGAvXZWB2+c7FUQ42DvdNHIHfNyE27pk/1OQXhpxirRJrUXzTXh2OsiLEFhFeMTq4R88WK2Y/OItd7L+PDL4oZ5mvHJNosJvQzLlk8QEUCZu77APK6K5UAnxcC8tPTH00kJR3kiIjKDFo5CxWQRujww9+x++TVZ2rJOkXndf4+6jDVuBbIuoPmLShMS71Oib6q0HNIKmhnwauLQ0A==" Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=debug msg="completed keyexchange" Mar 18 10:29:35 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=debug msg="new websocket client" Mar 18 10:29:35 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=debug msg="completed challenge" Mar 18 10:29:35 badezimmer go-librespot[32341]: time="2025-03-18T10:29:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Mar 18 10:29:35 badezimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 18 10:29:35 badezimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 18 10:29:35 badezimmer volumio[1012]: info: Connection to go-librespot Websocket closed Mar 18 10:29:36 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:37 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 18 10:29:37 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 18 10:29:37 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:37 badezimmer volumio[1012]: info: Discovery: Remote connection error: 192.168.1.111 Mar 18 10:29:38 badezimmer volumio[1012]: info: Getting Spotify volume Mar 18 10:29:38 badezimmer volumio[1012]: (node:1012) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:38 badezimmer volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 18 10:29:38 badezimmer volumio[1012]: (node:1012) 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: 501) Mar 18 10:29:38 badezimmer volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 18 10:29:38 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:38 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:38 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:38 badezimmer volumio[1012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 18 10:29:38 badezimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 18 10:29:38 badezimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1509. Mar 18 10:29:38 badezimmer systemd[1]: Stopped go-librespot Daemon. Mar 18 10:29:38 badezimmer systemd[1]: Started go-librespot Daemon. Mar 18 10:29:38 badezimmer go-librespot[32369]: Librespot-go daemon starting... Mar 18 10:29:38 badezimmer go-librespot[32369]: time="2025-03-18T10:29:38+01:00" level=info msg="generated new device id: f6c4f5b3eb924010d7fb6963b7253c0a7b184a7b" Mar 18 10:29:38 badezimmer go-librespot[32369]: time="2025-03-18T10:29:38+01:00" level=debug msg="stored credentials not found" Mar 18 10:29:39 badezimmer go-librespot[32369]: time="2025-03-18T10:29:39+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 18 10:29:39 badezimmer go-librespot[32369]: time="2025-03-18T10:29:39+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 18 10:29:39 badezimmer go-librespot[32369]: time="2025-03-18T10:29:39+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 18 10:29:39 badezimmer go-librespot[32369]: time="2025-03-18T10:29:39+01:00" level=debug msg="zeroconf server listening on port 41245" Mar 18 10:29:39 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 18 10:29:39 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 18 10:29:39 badezimmer volumio[1012]: info: Discovery: Getting this device information Mar 18 10:29:39 badezimmer volumio[1012]: info: CoreCommandRouter::volumioGetState Mar 18 10:29:39 badezimmer volumio[1012]: info: CorePlayQueue::getTrack 0 Mar 18 10:29:39 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 10:29:40 badezimmer volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 18 10:29:41 badezimmer volumio[1012]: info: Initializing connection to go-librespot Websocket Mar 18 10:29:41 badezimmer go-librespot[32369]: time="2025-03-18T10:29:41+01:00" level=debug msg="new websocket client" Mar 18 10:29:41 badezimmer volumio[1012]: info: Connection to go-librespot Websocket established Mar 18 10:29:42 badezimmer volumio[1012]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 10:29:42 badezimmer volumio[1012]: Error: dns service error: unknown Mar 18 10:29:42 badezimmer volumio[1012]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 18 10:29:42 badezimmer volumio[1012]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 18 10:29:42 badezimmer volumio[1012]: errorCode: -65537 Mar 18 10:29:42 badezimmer volumio[1012]: } Mar 18 10:29:42 badezimmer volumio[1012]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 10:29:42 badezimmer sudo[32387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 10:28 Mar 18 10:29:42 badezimmer sudo[32387]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"