-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-05-08 10:39:59 CEST. -- May 08 10:38:00 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:00 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. May 08 10:38:00 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:00 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:00 volumiob go-librespot[3288]: Librespot-go daemon starting... May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=info msg="generated new device id: 43bee3140eef8980ccf17ca663b9b4635e1871f3" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="zeroconf server listening on port 44727" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="obtained new client token: AADOo/xTXF64CvyTYf3ml5lv1v9VAVYb6rqxI87sepOkJExXjAW39sIyFm0BwhrrJCCHZRrVidCG3YJ7oxR0rqrj06uguh+YbkXr/aUfwCEDb+BnZj0twyClF9xbITQqQBUu0HmJADTqAK6mI/cTueeQaoVAtiwG7fIRcvE7KgBYAs0fiZKwHXPTcSBXLGhQI+w8W/iDxjaz9D7kG6vIFSu2HKDySUYHkqFtWmUqQB/EC5PaAZDJ6+Z11Q==" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:00 volumiob go-librespot[3288]: time="2025-05-08T10:38:00+02:00" level=debug msg="completed keyexchange" May 08 10:38:01 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:01 volumiob go-librespot[3288]: time="2025-05-08T10:38:01+02:00" level=debug msg="new websocket client" May 08 10:38:01 volumiob volumio[863]: info: Connection to go-librespot Websocket established May 08 10:38:01 volumiob go-librespot[3288]: time="2025-05-08T10:38:01+02:00" level=debug msg="completed challenge" May 08 10:38:01 volumiob go-librespot[3288]: time="2025-05-08T10:38:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:01 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:01 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:01 volumiob volumio[863]: info: Connection to go-librespot Websocket closed May 08 10:38:04 volumiob volumio[863]: info: Getting Spotify volume May 08 10:38:04 volumiob volumio[863]: (node:863) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:04 volumiob volumio[863]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) May 08 10:38:04 volumiob volumio[863]: (node:863) 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: 10) May 08 10:38:04 volumiob volumio[863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 08 10:38:04 volumiob volumio[863]: info: CoreCommandRouter::volumioGetState May 08 10:38:04 volumiob volumio[863]: info: CorePlayQueue::getTrack 0 May 08 10:38:04 volumiob volumio[863]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 08 10:38:04 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:04 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:04 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:04 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. May 08 10:38:04 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:04 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:04 volumiob go-librespot[3296]: Librespot-go daemon starting... May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=info msg="generated new device id: b5104f9efefbc9ad0e0d74c1c1c26542553bc9e8" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="zeroconf server listening on port 35295" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="obtained new client token: AAB5/X3/pCBSDFYbY21acprtua/jvyUM3qKebtWCv+LVrj7A1xHrU9LJvy75CyFmvKgAAQGpR7CaSqY+t1F48Vt4QV+tLMQ7EA0nAXQL8vELA3Qna9xJVlCh6ZwQEe0LmDrpY78mGs6FDYY72IwVj79ZTp1UkohjxRu9RjU+uK4/rRf7xH0hAqp/MUlX2W3hN2y1wDAvh4KsC3nLpTHsn9UeCrEDbfUwGsYnmtvvgrQLoeA0hWdWEu8eWw==" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:04 volumiob go-librespot[3296]: time="2025-05-08T10:38:04+02:00" level=debug msg="completed keyexchange" May 08 10:38:05 volumiob go-librespot[3296]: time="2025-05-08T10:38:05+02:00" level=debug msg="completed challenge" May 08 10:38:05 volumiob go-librespot[3296]: time="2025-05-08T10:38:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:05 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:05 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:06 volumiob volumio[863]: Searching all installed plugins May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: , search May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 08 10:38:06 volumiob volumio[863]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search May 08 10:38:06 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 08 10:38:07 volumiob volumio[863]: (node:863) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found May 08 10:38:07 volumiob volumio[863]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) May 08 10:38:07 volumiob volumio[863]: at /volumio/app/plugins/music_service/webradio/index.js:971:31 May 08 10:38:07 volumiob volumio[863]: at runMicrotasks () May 08 10:38:07 volumiob volumio[863]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 08 10:38:07 volumiob volumio[863]: (node:863) 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: 11) May 08 10:38:07 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:07 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:07 volumiob volumio[863]: Searching all installed plugins May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: , search May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 08 10:38:07 volumiob volumio[863]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search May 08 10:38:07 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 08 10:38:07 volumiob volumio[863]: info: searchTIDALUri took 696 milliseconds May 08 10:38:07 volumiob volumio[863]: info: search took 697 milliseconds May 08 10:38:07 volumiob volumio[863]: (node:863) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found May 08 10:38:07 volumiob volumio[863]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) May 08 10:38:07 volumiob volumio[863]: at /volumio/app/plugins/music_service/webradio/index.js:971:31 May 08 10:38:07 volumiob volumio[863]: at runMicrotasks () May 08 10:38:07 volumiob volumio[863]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 08 10:38:07 volumiob volumio[863]: (node:863) 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: 12) May 08 10:38:07 volumiob volumio[863]: info: searchTIDALUri took 404 milliseconds May 08 10:38:07 volumiob volumio[863]: info: search took 405 milliseconds May 08 10:38:08 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:08 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. May 08 10:38:08 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:08 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:08 volumiob go-librespot[3305]: Librespot-go daemon starting... May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=info msg="generated new device id: 379dd304e2053f7dcc0ef4e2bcb14dfb21ec7c80" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="zeroconf server listening on port 35299" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="obtained new client token: AABJP1aPVS2V4bFIZzNc6+NQ21ZHALlJTCP6jTu8ai1GILZACPraNItqy7cp/ueUecHdH8lb8kNKSVl0nhps9oZZZ5474e3wCKQmhum9f3p8rsk0O8W74SMFCQu4NdFbacBd7sLafY1veS1BaOM0znznQ+ezK0jsGhQdyVZKFF/l0/v0YTPokAuxNl/2xWGOtFUKFB1ArFfv0H5zMkSqf/gPc6+ZggtpHzdUBPs43rS19n+NuS9+QabIwg==" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:08 volumiob go-librespot[3305]: time="2025-05-08T10:38:08+02:00" level=debug msg="completed keyexchange" May 08 10:38:09 volumiob go-librespot[3305]: time="2025-05-08T10:38:09+02:00" level=debug msg="completed challenge" May 08 10:38:09 volumiob go-librespot[3305]: time="2025-05-08T10:38:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:09 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:09 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:09 volumiob volumio[863]: Searching all installed plugins May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: , search May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 08 10:38:09 volumiob volumio[863]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search May 08 10:38:09 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 08 10:38:10 volumiob volumio[863]: info: searchTIDALUri took 337 milliseconds May 08 10:38:10 volumiob volumio[863]: info: search took 338 milliseconds May 08 10:38:10 volumiob volumio[863]: info: All search sources collected, pushing search results May 08 10:38:10 volumiob volumio[863]: Searching all installed plugins May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: , search May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 08 10:38:10 volumiob volumio[863]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search May 08 10:38:10 volumiob volumio[863]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 08 10:38:10 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:10 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:10 volumiob volumio[863]: info: searchTIDALUri took 150 milliseconds May 08 10:38:10 volumiob volumio[863]: info: search took 151 milliseconds May 08 10:38:10 volumiob volumio[863]: info: All search sources collected, pushing search results May 08 10:38:11 volumiob volumio[863]: error: Search in plugin webradio timed out May 08 10:38:11 volumiob volumio[863]: info: All search sources collected, pushing search results May 08 10:38:12 volumiob volumio[863]: error: Search in plugin webradio timed out May 08 10:38:12 volumiob volumio[863]: info: All search sources collected, pushing search results May 08 10:38:12 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:12 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. May 08 10:38:12 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:12 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:12 volumiob go-librespot[3313]: Librespot-go daemon starting... May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=info msg="generated new device id: 7db407c53a09ec81e58b2a25cce4ec83bb72a891" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="zeroconf server listening on port 39237" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="obtained new client token: AAAfgIRwDJYpyj4YdrYYlEcf0r0blUs68AuQi0SS6+OBj4WN+Bg1EWM/WaWDNyLg9kIYrNjAbcHk5WyHW34diC9SkXeF7qP47gQxgGa+VPu40X/yiVyIyfIv+y1Z+yP0Ybqc7CyCdKntGiMEwm8cnJtMKgZzGMpMKTNc/70aUZ4OFUys6aGKthzAAgLvZaUmYUd95aIR5T1ujd5Y0FWHBrvQunxEz7sF9zkoxcwqV0oj23nzhlepzhHmfw==" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:12 volumiob go-librespot[3313]: time="2025-05-08T10:38:12+02:00" level=debug msg="completed keyexchange" May 08 10:38:13 volumiob go-librespot[3313]: time="2025-05-08T10:38:13+02:00" level=debug msg="completed challenge" May 08 10:38:13 volumiob go-librespot[3313]: time="2025-05-08T10:38:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:13 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:13 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:13 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:13 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:16 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:16 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:16 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:16 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. May 08 10:38:16 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:16 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:16 volumiob go-librespot[3321]: Librespot-go daemon starting... May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=info msg="generated new device id: a4feb4b3fad47f22c1a76642128de3b104b539b0" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="zeroconf server listening on port 44781" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="obtained new client token: AAC64OBJN4SXFU6g0AC7iQhrxt79AStH7KmHaV7NanLU/vVpg3qXZ7SJBtpsmNj9kDppKExBlaPhwFTQ3TJ0Y4v6JyLJcuX+iqpBpnosqaLxh4ezdfHsdJYQQmJO+7CHniW3jDIZRQTWhNPNUQdQVS2aaXux7dJrqzDk11bQFGLeGlMxRmXIbfO5dHHCBJWWakf/xPQ0lgmCbMldepJzxx73K5qDILyYQdICJjNSGjTi2Y/3BC0lX32g9Q==" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:16 volumiob go-librespot[3321]: time="2025-05-08T10:38:16+02:00" level=debug msg="completed keyexchange" May 08 10:38:17 volumiob go-librespot[3321]: time="2025-05-08T10:38:17+02:00" level=debug msg="completed challenge" May 08 10:38:17 volumiob go-librespot[3321]: time="2025-05-08T10:38:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:17 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:17 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:19 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:19 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:20 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:20 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. May 08 10:38:20 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:20 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:20 volumiob go-librespot[3370]: Librespot-go daemon starting... May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=info msg="generated new device id: 62bd8c3ab79e90ae1b3845c49101864360a6b9cb" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="zeroconf server listening on port 33861" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="obtained new client token: AACRQ7igzQFRxtjkpfhy2EH/IMYxybhTEkXjlMX2TPIED4bL4H3Ik/DaRPulpldu0jyHqMlFXFMn8EBGMKcw8o6t+ZfcfFqb8uAaxtseu/xC6Dbe461CJ/THK8/J1jBXqnc451S4SCFJERN5pblim11ReLvzO6UU12hodhKebGs9xdAFIx1IOVfiBMawSgnzWQTK+XKN7N1bFaaj76YAR7d4JVaseWM/BE+vEfNwpZIwtFH+OC7IF553Xw==" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:20 volumiob go-librespot[3370]: time="2025-05-08T10:38:20+02:00" level=debug msg="completed keyexchange" May 08 10:38:21 volumiob go-librespot[3370]: time="2025-05-08T10:38:21+02:00" level=debug msg="completed challenge" May 08 10:38:21 volumiob go-librespot[3370]: time="2025-05-08T10:38:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:21 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:21 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:22 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:22 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:24 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:24 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. May 08 10:38:24 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:24 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:24 volumiob go-librespot[3378]: Librespot-go daemon starting... May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=info msg="generated new device id: 9f27a7dff46ec340264be3271e72a0f5cf6bfe73" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="zeroconf server listening on port 40483" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="obtained new client token: AAAfawxXSV/9kIqlnETCwRWqTm+krBtxfgBOO5RgteN8RO6VtWDPmn/BPWwdFl+/N/3lC2h1QqhfPDviabElHgdPywDtyGc0RN8FcJYGd/ItNme/x0kPmcBrcxlfkQ9j2jqmqIPESr4R1bf0etM/u73oV7e+ON0GEziJb1y9ekuILex2w/wDQ0QsRqY6veXLlzoLdlwYVVXJAIcB4CMWxVif3yhqB9wOHnXizhnM1K0fnMbJX+hitJL3cQ==" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:24 volumiob go-librespot[3378]: time="2025-05-08T10:38:24+02:00" level=debug msg="completed keyexchange" May 08 10:38:25 volumiob go-librespot[3378]: time="2025-05-08T10:38:25+02:00" level=debug msg="completed challenge" May 08 10:38:25 volumiob go-librespot[3378]: time="2025-05-08T10:38:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:25 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:25 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:25 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:25 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:28 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:28 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:28 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:28 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. May 08 10:38:28 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:28 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:28 volumiob go-librespot[3387]: Librespot-go daemon starting... May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=info msg="generated new device id: 98be62fd406f775b3972e29578be45a84cc82e6f" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="zeroconf server listening on port 37095" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="obtained new client token: AACeddMFXI4aGSwrknDvx/OyRdqc/qA2g9Ehquip7l/pL2VZ+F6x3NqM6lxNfZunnKDs4S1fZQAHP7KS5YUBOvfRY3RDtv246vGF1QeO/S67o+rpiN0PrphxozfPtWdI4yLxGTxEwrT5ZfDxlzuP/Haj6LrzFjeHuimvJjInKaRIoCnJts/FitXM30ml1E9ySlyhYUvQa4xzSluarU/aH9uDZGv8B0TQVzE9vm7XTvUPv5/qHUfGJ2DrIA==" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:28 volumiob go-librespot[3387]: time="2025-05-08T10:38:28+02:00" level=debug msg="completed keyexchange" May 08 10:38:29 volumiob go-librespot[3387]: time="2025-05-08T10:38:29+02:00" level=debug msg="completed challenge" May 08 10:38:29 volumiob go-librespot[3387]: time="2025-05-08T10:38:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:29 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:29 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:31 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:31 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:32 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:32 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130. May 08 10:38:32 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:32 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:32 volumiob go-librespot[3395]: Librespot-go daemon starting... May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=info msg="generated new device id: 3f10608c94312db5d1830632ab11eed5b295ae78" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="zeroconf server listening on port 45915" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="obtained new client token: AAAdU6FSO4U/rvqlG+qKyxDKTRtJsKxST99arW64MK3rrVM6Gt6YdOCHLvcyTBa56CusUZSn1yVVdbfKLJpqaTvqCEvFZwwnScQXzLVD1apzAJ08aqzB76au+mChup2uRXMIbdN8fUEs83QTFcBLTKrnDdePDTIrBpGmzWzwFBdmYRc8LDFv0vo//6u//CL4rHvzig5jLGu7/zgIaJR2pnZJHptf3l87IZRknsovdvnJ1xQMUbXZWAQDEg==" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:32 volumiob go-librespot[3395]: time="2025-05-08T10:38:32+02:00" level=debug msg="completed keyexchange" May 08 10:38:33 volumiob go-librespot[3395]: time="2025-05-08T10:38:33+02:00" level=debug msg="completed challenge" May 08 10:38:33 volumiob go-librespot[3395]: time="2025-05-08T10:38:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:33 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:33 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:34 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:34 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:36 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:36 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131. May 08 10:38:36 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:36 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:36 volumiob go-librespot[3403]: Librespot-go daemon starting... May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=info msg="generated new device id: 3efb472661bb6b56b6e73f722ee3a7d099e9b216" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="zeroconf server listening on port 46701" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="obtained new client token: AAA1NTTQuPUE3pWsL3fqPZOjcfx2r7EDezk2pYcCq1L0xlNLRxWZSvQSNHhrRzJDg5OFy1JqTG+nebR+8usC3UPeb5+CGoFLC5aReIHNMdbQQRKgEy+apdtbEqTBbMe6vQdaE/CmdXAHJzy6tazXzyehgqN+6O3cHO12dY7QbjP1xnmFXsblNINTTGAJq0+wUv4plEGomGMLnxX8y215QqPXmbylYtI9AqIWKBkGj8oWva5EgdBq0097Zg==" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:36 volumiob go-librespot[3403]: time="2025-05-08T10:38:36+02:00" level=debug msg="completed keyexchange" May 08 10:38:37 volumiob go-librespot[3403]: time="2025-05-08T10:38:37+02:00" level=debug msg="completed challenge" May 08 10:38:37 volumiob go-librespot[3403]: time="2025-05-08T10:38:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:37 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:37 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:37 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:37 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:40 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:40 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:40 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:40 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 132. May 08 10:38:40 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:40 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:40 volumiob go-librespot[3411]: Librespot-go daemon starting... May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=info msg="generated new device id: c6a24c8f503795efaad56cb3add3153d84b886ee" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="zeroconf server listening on port 33971" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="obtained new client token: AAB2SQ0qAr+ElNe8KbEtxewkxbrxgel+/Scj0PZsXpefdN2rss+brjlplh62gqTdY08G7wRFZEBiNBDJWpPzOEplStE0Nrgu6v0g7L0tE7Wa4OFO1DvLvzG52TUVy4Q286Cl1adyz0hUPMOAb6AZ7wdo3b4pR/wbRryLmBza74KiyR93Jq6N1laK2/VVgrX8byVHL9V7pzGQMirvr+gb5W2FdO0NqTVUWnnl/jOqA/MA4FbbqkohLJlcWA==" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:40 volumiob go-librespot[3411]: time="2025-05-08T10:38:40+02:00" level=debug msg="completed keyexchange" May 08 10:38:41 volumiob go-librespot[3411]: time="2025-05-08T10:38:41+02:00" level=debug msg="completed challenge" May 08 10:38:41 volumiob go-librespot[3411]: time="2025-05-08T10:38:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:41 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:41 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:43 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:43 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:44 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:44 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 133. May 08 10:38:44 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:44 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:44 volumiob go-librespot[3419]: Librespot-go daemon starting... May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=info msg="generated new device id: 951fff7db1c5f0248af4f180a3e63000f9a8b686" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="zeroconf server listening on port 45859" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="obtained new client token: AACA3eXd1tjc+uXfZHUTkC9Nq+uulN2LRtx0BkwsNL/jfvRiTASUjTD+K6do74H7hxmJNWM67kfAIGS7e0qEbn0szGD73oeZBS4kQbsQBm2jFAUl7kSlVpqES75XWGtvWqe1wQT4EbdHjmYBdkBaAXbbON4wZlQaRDfXToWQ6Wpo+lSyEKahrF3LtJ0iUTPgrlpAhPVHud007KulSI3hUmvirm8+sCeaQTw+2sROeZrsLw2Ep78qm5JoaQ==" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:44 volumiob go-librespot[3419]: time="2025-05-08T10:38:44+02:00" level=debug msg="completed keyexchange" May 08 10:38:45 volumiob go-librespot[3419]: time="2025-05-08T10:38:45+02:00" level=debug msg="completed challenge" May 08 10:38:45 volumiob go-librespot[3419]: time="2025-05-08T10:38:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:45 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:45 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:46 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:46 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:48 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:48 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 134. May 08 10:38:48 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:48 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:48 volumiob go-librespot[3428]: Librespot-go daemon starting... May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=info msg="generated new device id: 746f96fd641f58d8761dee6819a78f92600549ac" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="zeroconf server listening on port 36309" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="obtained new client token: AACo9UPbAOQKyHuXRhNGDpPKEwEYvzm/9Dy8dgVtMpHgIQSKV7X7c1J1NzEINR4fEzMhiimsqom3frLB5MHfEbWX8amuot22Jar708EQbMtsKrTIk7bw0PuAA5CP+etP7HenAWUka8q5WTjGhWZK7OU0Qz5cpR2mOZfnDVbQSzRMnx4G+We96L82DLawVUH+LxL/oKEN06X1DmFQD/yaHjRhF9NKHRtk8SKQIqC+o0NPXVvWt10NDhLJFg==" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:48 volumiob go-librespot[3428]: time="2025-05-08T10:38:48+02:00" level=debug msg="completed keyexchange" May 08 10:38:49 volumiob go-librespot[3428]: time="2025-05-08T10:38:49+02:00" level=debug msg="completed challenge" May 08 10:38:49 volumiob go-librespot[3428]: time="2025-05-08T10:38:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:49 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:49 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:49 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:49 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:52 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:52 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:52 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:52 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 135. May 08 10:38:52 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:52 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:52 volumiob go-librespot[3436]: Librespot-go daemon starting... May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=info msg="generated new device id: ecd7b8d177ce0d640fe0afd74f7715329a8f0de7" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="zeroconf server listening on port 36541" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="obtained new client token: AAB29cjWYxABh4VeVLETFtGsoYGr9CE+sQ/r2KrimjuaBgevqF2H6/sq0kfMoEMY8pSRPlBo7aWbrZY8y81u1r0phKaQqDTQ16FwauwsJeh9CklRuCEw2+93V4sjBRpjEXmhizZu1DIEAMYyVX/aNkFHDt6On5QgsHrF+ilTYwEGKRYUW1yos5scmo1nbms8wpLUyCzglyZ7E8O1TDPXSZjSUig9NEkbQzE/QVmoIOq4tQiEvx+tvZQI8A==" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:52 volumiob go-librespot[3436]: time="2025-05-08T10:38:52+02:00" level=debug msg="completed keyexchange" May 08 10:38:53 volumiob go-librespot[3436]: time="2025-05-08T10:38:53+02:00" level=debug msg="completed challenge" May 08 10:38:53 volumiob go-librespot[3436]: time="2025-05-08T10:38:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:53 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:53 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:55 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:55 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:38:56 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:38:56 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 136. May 08 10:38:56 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:38:56 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:38:56 volumiob go-librespot[3444]: Librespot-go daemon starting... May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=info msg="generated new device id: 617f476f7882235712d0429295f13124a57c44fd" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="zeroconf server listening on port 43191" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="obtained new client token: AACV5uwbnBcgl/n4l2Ciz7yvA1/HL+ucmdAzXbl34OWEheN6b4reyuFcapVy6ydUJQgmsPivOc2VGT1IpuyNn6BmncXnZd355/+xpuNSdJkJBwtr8u2h1ZZ3AsmR4VLIvQXUEFRZirlAkZ0Sn0UJL4kFEF8LVRSExkst4ghpd7Txr57XaCHUj0qFKaxzFl6e38OaPvrRF+O1NkRSpBYtYKem48qojmK8XTiPyGT4zkZ/xFrNZMOigBDdsA==" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:38:56 volumiob go-librespot[3444]: time="2025-05-08T10:38:56+02:00" level=debug msg="completed keyexchange" May 08 10:38:57 volumiob go-librespot[3444]: time="2025-05-08T10:38:57+02:00" level=debug msg="completed challenge" May 08 10:38:57 volumiob go-librespot[3444]: time="2025-05-08T10:38:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:38:57 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:38:57 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:38:58 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:38:58 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:00 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:00 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 137. May 08 10:39:00 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:00 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:00 volumiob go-librespot[3453]: Librespot-go daemon starting... May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=info msg="generated new device id: 59edde3bb885d422c8b023ad7e43691e93553604" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="zeroconf server listening on port 38797" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="obtained new client token: AACl/Z+Apmkl9WyZgVrTeitwcCNVGesQvT0ChUtapCdELH3GTWkqHRyjLyiIXdZVO4sM4XqIoUOMu6MpsKysVvPmGOjVE1uTgc6FSkpBzvCWu2tAAQLes1Q0cP9OkdGulw3RIX4avbF2w5ckp8hSMihCT/W67hQ1FOTYYHR0BSqTlat95mySBpNYk4R/DQuNIf23vw9oD2qJI0PjYN9EcBZVi1SuGVCkFsx8LVpAg9mF0uMERw67V7SMYw==" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:00 volumiob go-librespot[3453]: time="2025-05-08T10:39:00+02:00" level=debug msg="completed keyexchange" May 08 10:39:01 volumiob go-librespot[3453]: time="2025-05-08T10:39:01+02:00" level=debug msg="completed challenge" May 08 10:39:01 volumiob go-librespot[3453]: time="2025-05-08T10:39:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:01 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:01 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:01 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:01 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:04 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:04 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:04 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:04 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 138. May 08 10:39:04 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:04 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:04 volumiob go-librespot[3461]: Librespot-go daemon starting... May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=info msg="generated new device id: a84d70cedd8ddc06b760dc7a4d8667b050fd449c" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="zeroconf server listening on port 38293" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="obtained new client token: AAANWGSXb3xyEXTl9d2QiG+e2RzQgoBahiVqaZpFq1VOSZkroZGjZVFMwsibnb+WKew4MEKuWu1DrlhLRf+8UbzxpWcswQxwuBOp17nsx/TaK9UAVm31854H8ZKX+WhzAEbMRJI7Yk8Fds9D56MsoEuxVQ/DeHckDmbnBr3iMwKGNNyawmN5bzM5EdU5ouQ8OkrgOSP4vyeqBRsy3V/QVEi2dN4W9fPxydbJScCYcaz+J9+QvIMLQs98nA==" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:04 volumiob go-librespot[3461]: time="2025-05-08T10:39:04+02:00" level=debug msg="completed keyexchange" May 08 10:39:05 volumiob go-librespot[3461]: time="2025-05-08T10:39:05+02:00" level=debug msg="completed challenge" May 08 10:39:05 volumiob go-librespot[3461]: time="2025-05-08T10:39:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:05 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:05 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:07 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:07 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:08 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:08 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 139. May 08 10:39:08 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:08 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:08 volumiob go-librespot[3469]: Librespot-go daemon starting... May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=info msg="generated new device id: df4b3c6ac9174c3d4c00ddca0aca849c857a9117" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="zeroconf server listening on port 43283" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="obtained new client token: AABcQIRK6JzIZBMjhiyfjzCO2HeZyW+OYX2PdONTJeVIfPlkZD2kS/+hFcVbPX4aBOuxaSu4imNKQdvlMPdzBPdos3OC9FLuMg79qSCRRqcXgN6sa5IVDERQgJw9bchw5eGs5druL8woxERbsezbMJDh0hHdi3QuF1pKhfCaQXMI41AAUbigBq5lDhh/QzosmmCMsE0DUhuhu+ChNx4Zrek6r2PsCzQR3j9LIFoI3rvnz2MhrCFxMRmi/A==" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:08 volumiob go-librespot[3469]: time="2025-05-08T10:39:08+02:00" level=debug msg="completed keyexchange" May 08 10:39:09 volumiob go-librespot[3469]: time="2025-05-08T10:39:09+02:00" level=debug msg="completed challenge" May 08 10:39:09 volumiob go-librespot[3469]: time="2025-05-08T10:39:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:09 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:09 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:10 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:10 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:12 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:12 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 140. May 08 10:39:12 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:12 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:12 volumiob go-librespot[3478]: Librespot-go daemon starting... May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=info msg="generated new device id: 91ddf2d30da1c34c18fbda68a2ae4320d0bd0d7b" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="zeroconf server listening on port 41509" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="obtained new client token: AAB7uDMPi1VAFSPltu8cbR3ZQKfxxA2IwgIhLq4i/fWC8llzMi6Q1W48ZihBDlDnsSAuD27BEOOFdgwYEZetqWW6AhADHvgYXr0mPZYif1Db/Hmz4Ctswlz15YVhEdyihpxPgviuUZQweayxWHVJtPnXfJuVVDe68VIDw0ZQxjI7Ykz1UnHJ3FN/vSvC+nn3zucknnurj/jgEdwP34aeXj33d6HZXMQYjTjONnNlhVLKKykIZws4nZxgug==" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:12 volumiob go-librespot[3478]: time="2025-05-08T10:39:12+02:00" level=debug msg="completed keyexchange" May 08 10:39:13 volumiob go-librespot[3478]: time="2025-05-08T10:39:13+02:00" level=debug msg="completed challenge" May 08 10:39:13 volumiob go-librespot[3478]: time="2025-05-08T10:39:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:13 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:13 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:13 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:13 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:16 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:16 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:16 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:16 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 141. May 08 10:39:16 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:16 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:16 volumiob go-librespot[3487]: Librespot-go daemon starting... May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=info msg="generated new device id: 100590bc1fd9b796f844db8c95d0abe15abe31b7" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="zeroconf server listening on port 44829" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="obtained new client token: AAC9fVsZaGY2fF2DRzGA89UkVX1gVSo6ZO8GPUc2LahuqmxKOPTn4MFEuI/UYVZGHXmBQmvywE07IGeBKOMQqTxxp6GrfKu0sehyV/z+zJYKbG5uzCyo/Upe3VfXuvK1YTnS/MyARy6rd2QzOkQX+Rh6udEHCpuEUXgs3QyOl0AkorAYQIZ9lKWe8ZfRg51+TsRXBBHYi8XelRWSruaeB6UKnRxo/bZU+kSav8JCJMwRvox0Jp5VGHn+Cw==" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:16 volumiob go-librespot[3487]: time="2025-05-08T10:39:16+02:00" level=debug msg="completed keyexchange" May 08 10:39:17 volumiob go-librespot[3487]: time="2025-05-08T10:39:17+02:00" level=debug msg="completed challenge" May 08 10:39:17 volumiob go-librespot[3487]: time="2025-05-08T10:39:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:17 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:17 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:19 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:19 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:20 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:20 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 142. May 08 10:39:20 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:20 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:20 volumiob go-librespot[3536]: Librespot-go daemon starting... May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=info msg="generated new device id: a26d3c55097ee98f6809a8ae6c1115f5663eaf5e" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="zeroconf server listening on port 43979" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="obtained new client token: AADKqMZdYJjPXmCFQVusGkfi/MTkppZs3t/h2Ygt2OhDrAx2n0StqPpyokHsrnVumMRsiZdog3e3Texx2sY73LQ5pLX4S/eyApTkXATo+O+jXaPlWC0u26/Cf5O5+FYo7q2T0Ga/jFez29/XUyuAg9HVlO77Moh/ZzBlZ3+RqmCdxnZTS8mf0bYNqLIZgmhf3q/chCNynbtBi+Cuhk7q9tzvPGCXl/tPPTx5sZUS1H09sjWwyVqGQkD9Zg==" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:20 volumiob go-librespot[3536]: time="2025-05-08T10:39:20+02:00" level=debug msg="completed keyexchange" May 08 10:39:21 volumiob go-librespot[3536]: time="2025-05-08T10:39:21+02:00" level=debug msg="completed challenge" May 08 10:39:21 volumiob go-librespot[3536]: time="2025-05-08T10:39:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:21 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:21 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:22 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:22 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:24 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:24 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 143. May 08 10:39:24 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:24 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:24 volumiob go-librespot[3544]: Librespot-go daemon starting... May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=info msg="generated new device id: 59c527bc17b923b37be89809ccb0ce0fc5cf4e94" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="zeroconf server listening on port 33693" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="obtained new client token: AADGsQVl21BnTAwJ+sds2CIgpFc7d0KSlLYXvppHDdhV8sZxKDQaxr0PYa8hm5oVEFMKjJWuTc8uetXLY5JS3picwtkwtYquwbj66P5HVA/iQ3pB0RmoVNnavfRWZno0eh8VdqYezQNBT4ll98zpopISxSQZIEhEaKpsW6P/gt7KOPXj3PGH/1cTfLBNq0PmBkVMozPLS0emvai24l82//ofL8GB8gnzqRPK9ijkNcSyLf4CWNVU+IucDQ==" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:24 volumiob go-librespot[3544]: time="2025-05-08T10:39:24+02:00" level=debug msg="completed keyexchange" May 08 10:39:25 volumiob go-librespot[3544]: time="2025-05-08T10:39:25+02:00" level=debug msg="completed challenge" May 08 10:39:25 volumiob go-librespot[3544]: time="2025-05-08T10:39:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:25 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:25 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:25 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:25 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:27 volumiob ntpd[661]: 90.68.206.60 local addr 192.168.0.178 -> May 08 10:39:28 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:28 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:28 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:28 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 144. May 08 10:39:28 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:28 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:28 volumiob go-librespot[3552]: Librespot-go daemon starting... May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=info msg="generated new device id: 2ff9d3d0eee47df03d34f0d33687eb774cf72ab0" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="zeroconf server listening on port 45453" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="obtained new client token: AABkXy2mPlbAVKwDdLoPMAOCF1fTpY2C0FjYQe6fXp32R10NORBajIDntH9hUb2BRGN2/6vPFve7cQNyMddxQcgkrw+zmf88TOJ9FvFzfdSKtY5Dp84Q1vdzBtHaHPLrSyKr3erLtK1f7o3iAWT0VPG2RMkFjWbZ51QAhazSE5NT71R9zozNhjsxkTJwWRtiTiynFUSsEYktwVIkw3oTF9EZ3x4MoaCohbLQ5AiIrJp26IQ6seAwBcuEkQ==" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:28 volumiob go-librespot[3552]: time="2025-05-08T10:39:28+02:00" level=debug msg="completed keyexchange" May 08 10:39:29 volumiob go-librespot[3552]: time="2025-05-08T10:39:29+02:00" level=debug msg="completed challenge" May 08 10:39:29 volumiob go-librespot[3552]: time="2025-05-08T10:39:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:29 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:29 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:31 volumiob ntpd[661]: 84.77.195.114 local addr 192.168.0.178 -> May 08 10:39:31 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:31 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:32 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:32 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 145. May 08 10:39:32 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:32 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:32 volumiob go-librespot[3561]: Librespot-go daemon starting... May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=info msg="generated new device id: e1883f24a37c14faf90d693baaaab843aa89118a" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="zeroconf server listening on port 45145" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="obtained new client token: AADJgr3u53TXSdn+1y6qg7EY2C/3msFnpNdaYacvWOx+jDWylCHuISgt1N/90GlGNG4KJ60vBePvCPDqqSoMxWd0XhOpN48ZZdztmA67uz3t3qg0l/DnPmPoXLMwGpsqwxq8W9IDXotbmeCXQc98fvvBjfuezlF2B9eU4B3FjaPnVTPU5n/Itw8JynFoJEMt3q7dr/PKm96gH1YnvBWgeYCxCB27tUM7TOE0GpPTKjfOk9xcVjMYX9RWxw==" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:32 volumiob go-librespot[3561]: time="2025-05-08T10:39:32+02:00" level=debug msg="completed keyexchange" May 08 10:39:33 volumiob go-librespot[3561]: time="2025-05-08T10:39:33+02:00" level=debug msg="completed challenge" May 08 10:39:33 volumiob go-librespot[3561]: time="2025-05-08T10:39:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:33 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:33 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:34 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:34 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:36 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:36 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 146. May 08 10:39:36 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:36 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:36 volumiob go-librespot[3570]: Librespot-go daemon starting... May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=info msg="generated new device id: 273dd16d29eda24d630fce9c79ae3013cf094f19" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="zeroconf server listening on port 46205" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="obtained new client token: AAACcUDuqkP4ZUB/nDif2yeQ4K+oqC58SHvXxcsXyqb1+cUWX41lAb3cWrBJKR1+bGpZTzXVkNgjkQpCPWdkWJSNYeEw2jrhknm7BjLXZoFGuz1DhPBuTdVnwwHs1kHwoStPZU5X5oFg6k6psb/wgd0cplAEvQJjDw7QJn/UcHAVKMXpijb29c+VjN8A/r48Q8i7EBE2Y70qFRTXGHvyq/MjdB9uCC0A/1fK+lPoaVSIMCHxYvJQJ/hNOQ==" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:36 volumiob go-librespot[3570]: time="2025-05-08T10:39:36+02:00" level=debug msg="completed keyexchange" May 08 10:39:37 volumiob go-librespot[3570]: time="2025-05-08T10:39:37+02:00" level=debug msg="completed challenge" May 08 10:39:37 volumiob go-librespot[3570]: time="2025-05-08T10:39:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:37 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:37 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:37 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:37 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:40 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:40 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:40 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:40 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 147. May 08 10:39:40 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:40 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:40 volumiob go-librespot[3578]: Librespot-go daemon starting... May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=info msg="generated new device id: 5ca210026c49f47cb5c8efd4c59957abf9aba1a5" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="zeroconf server listening on port 36093" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="obtained new client token: AABpWoExNmY0TIsUJikkol+/YuMoDXJ9FbTmrrgPSUUmh7U2tmSQDeV8VHl2hwXmUs9hjvbm4YKOo0w1AbiqFOcYk1UepM1/IDeUHdANELzgLjqovPZBm03yb+byAo6s5k7buE9kIVM09ymdCVQxe3fh6hZuk4l+/CRM6+LXWWDRSXWLxxjaIWT0mGjtpo7hlvTLYToVtS2O4VfocyU21HEdK1Yd1D3YXbWzARGDt9a8TMcwxlLWQ4hMyA==" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp 104.199.65.9:4070: connect: connection refused), retrying with a different AP" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=info msg="connected to ap-gew1.spotify.com:443" May 08 10:39:40 volumiob go-librespot[3578]: time="2025-05-08T10:39:40+02:00" level=debug msg="completed keyexchange" May 08 10:39:41 volumiob go-librespot[3578]: time="2025-05-08T10:39:41+02:00" level=debug msg="completed challenge" May 08 10:39:41 volumiob go-librespot[3578]: time="2025-05-08T10:39:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:41 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:41 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:43 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:43 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:44 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:44 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. May 08 10:39:44 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:44 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:44 volumiob go-librespot[3586]: Librespot-go daemon starting... May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=info msg="generated new device id: 31d3c08a1088f782c4ca5773bb4a6be7845f54a0" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="zeroconf server listening on port 33733" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="obtained new client token: AAANvLj8Cqx09opGqFKsACbQPhk5FI/WOAayEepIKBXKUoIdLyAdwhUnSVcgJqg4NvpyeBtyQgb6ErLODJycPKerQOB1bHYg5RS5aBLQKLGNEG17lrYc4Mu50leqzYvoR4lZvZzSeMZGBrq45mwmnKeaHok4A9giQ+3NkrGETuWs945lWowJM/I9y2CXoAKkO4rH9CbokOknvqxxn2222zluX/7kgBtPb7DnD7UomGCRAdIaHEM6qeKhcg==" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:44 volumiob go-librespot[3586]: time="2025-05-08T10:39:44+02:00" level=debug msg="completed keyexchange" May 08 10:39:45 volumiob go-librespot[3586]: time="2025-05-08T10:39:45+02:00" level=debug msg="completed challenge" May 08 10:39:45 volumiob go-librespot[3586]: time="2025-05-08T10:39:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:45 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:45 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:46 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:46 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:48 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:48 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. May 08 10:39:48 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:48 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:48 volumiob go-librespot[3594]: Librespot-go daemon starting... May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=info msg="generated new device id: e0b6c0991183cf3b3be239a71e6da755d234e8ad" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="zeroconf server listening on port 36635" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="obtained new client token: AABd8GLpUZnYIA1ZO09RDpqDHhCV18zbC4CZwIau7i8u5ra4A+hkZngm8y2ehJgMMYEUL1d0F2Gr2WDtJF+JwiGtrcExN1p8s+raMtlpTY82RbkMRvC3qvsUC2ICS2Qgu3p6PInZr0tlNb9ChfI4Ryd4dlzrO5G4IX965YIYUVPR7YEwdbpnKo4CuiAo0zw7d0TxcarXZ2GcBrQFPJcU7vlN6p85M/0ptyTJTjgkOjrByAxqi/OKb7scAA==" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:48 volumiob go-librespot[3594]: time="2025-05-08T10:39:48+02:00" level=debug msg="completed keyexchange" May 08 10:39:49 volumiob go-librespot[3594]: time="2025-05-08T10:39:49+02:00" level=debug msg="completed challenge" May 08 10:39:49 volumiob go-librespot[3594]: time="2025-05-08T10:39:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:49 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:49 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:49 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:49 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:52 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:52 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:52 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:52 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. May 08 10:39:52 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:52 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:52 volumiob go-librespot[3602]: Librespot-go daemon starting... May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=info msg="generated new device id: b1b1f1c18a7f2bcc3d15915fc0829c8899c88742" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="zeroconf server listening on port 45641" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="obtained new client token: AAA2uAXsNbSdLLirr4Yjca6SowPPkStC3R0531F1LdOYivYrmD/FuBJ8NvSXbkPkX7o0xnLFb8DRHcnySIwOhGSHrSQ9FPD1iS0eUqBNWWifgo0wxhtkCCMKxf7rZmz1j2m5Uhy9CUgggCdHw+j+gcwxP/W0AYLHl3E2ue5JV/F5exhLIMPnttM54S2tfohAn8ahAkwTpvypaNbw+f3fd7KLemKt0VvCubaeteN5Qq+zxne0QvZInm7nWw==" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:52 volumiob go-librespot[3602]: time="2025-05-08T10:39:52+02:00" level=debug msg="completed keyexchange" May 08 10:39:53 volumiob go-librespot[3602]: time="2025-05-08T10:39:53+02:00" level=debug msg="completed challenge" May 08 10:39:53 volumiob go-librespot[3602]: time="2025-05-08T10:39:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:53 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:53 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:55 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:55 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:56 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 08 10:39:56 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 151. May 08 10:39:56 volumiob systemd[1]: Stopped go-librespot Daemon. May 08 10:39:56 volumiob systemd[1]: Started go-librespot Daemon. May 08 10:39:56 volumiob go-librespot[3613]: Librespot-go daemon starting... May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=info msg="generated new device id: 3847a051d499cd477388cbc206b07668c89ec3b7" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="zeroconf server listening on port 32835" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="obtained new client token: AAD53iZgoTxnXWSIUzBcKsDjHNlWtkZ/a4iODtmqHRgeOD0aSG5ZX7DjlIdgQK7BJw7mKnce+Fr1KhpwH0hGu/F56NDqW0GUefkaCuKHq0L6qOZvYEbTk+cVJF1XMo+quTs82b/7LfkeIEoD68FQFKQdyDpfVIpWXn2PlihGQK8JU7fUGGhpBQiMwkj6wuEZQrROwMWA+Ery7kPBQEochZxxtkPr/MFniJw9zK3ofTYfADuBdzLggYkD+w==" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 08 10:39:56 volumiob go-librespot[3613]: time="2025-05-08T10:39:56+02:00" level=debug msg="completed keyexchange" May 08 10:39:57 volumiob go-librespot[3613]: time="2025-05-08T10:39:57+02:00" level=debug msg="completed challenge" May 08 10:39:57 volumiob go-librespot[3613]: time="2025-05-08T10:39:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 08 10:39:57 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 08 10:39:57 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 08 10:39:58 volumiob volumio[863]: info: Initializing connection to go-librespot Websocket May 08 10:39:58 volumiob volumio[863]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 08 10:39:58 volumiob volumio[863]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 10:39:58 volumiob volumio[863]: Error: connect ETIMEDOUT 151.101.134.79:80 May 08 10:39:58 volumiob volumio[863]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 08 10:39:58 volumiob volumio[863]: errno: -110, May 08 10:39:58 volumiob volumio[863]: code: 'ETIMEDOUT', May 08 10:39:58 volumiob volumio[863]: syscall: 'connect', May 08 10:39:58 volumiob volumio[863]: address: '151.101.134.79', May 08 10:39:58 volumiob volumio[863]: port: 80 May 08 10:39:58 volumiob volumio[863]: } May 08 10:39:58 volumiob volumio[863]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 10:39:59 volumiob sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 10:38 May 08 10:39:59 volumiob sudo[3630]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"