-- Logs begin at Sun 2025-03-09 20:28:53 CST, end at Sun 2025-03-09 23:01:14 CST. -- Mar 09 23:00:00 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:00 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:00 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:00 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4729. Mar 09 23:00:00 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:00 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:00 dac.x2-nvme go-librespot[16588]: go-librespot daemon starting... Mar 09 23:00:00 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:00+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:00 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:00+08:00" level=debug msg="app state loaded" Mar 09 23:00:00 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:00+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:00 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:00+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=info msg="zeroconf server listening on port 42231" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="obtained new client token: AAAAXjqSVIlrhh1j3amEXJi84BzM9lBH/QM+/5mNuAcYby3gGc/aTfyl7hpOxmzUmjF/oJpvTTyARIdyZ9LBC4J/E47vexNdhN7XIrotvc1wiC0vyjhxZWlYDJnY4rMlgFWXYZ3dDht7ceOrNAzN3avNuGkNVJBVsO3nQ284o8U5oE5D8feyBJKKPgtw2Ja64vhyKEiKP9KG9qKaIydQXeu1j0yCULAlPfAuWiL6tqSsCZIg9kcKcQHPWw==" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="connected to ap-gae2.spotify.com:443" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:01 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:01+08:00" level=debug msg="completed challenge" Mar 09 23:00:02 dac.x2-nvme go-librespot[16588]: time="2025-03-09T23:00:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:02 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:02 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:02 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: Discovery: Getting this device information Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 23:00:03 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:03 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:05 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:05 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4730. Mar 09 23:00:05 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:05 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:05 dac.x2-nvme go-librespot[16623]: go-librespot daemon starting... Mar 09 23:00:05 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:05+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:05 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:05+08:00" level=debug msg="app state loaded" Mar 09 23:00:05 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:05+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:05 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:06 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:06 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:06+08:00" level=debug msg="new websocket client" Mar 09 23:00:06 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:06 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:06+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:00:06 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:06+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:00:06 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:06+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:00:06 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:06+08:00" level=info msg="zeroconf server listening on port 39071" Mar 09 23:00:07 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:07+08:00" level=debug msg="obtained new client token: AADo32v6vqthnK59TQliHZfP8ldL6h4dmI+9cTXj+etbGc1T38lRl85t1jEiOmwhyxLjbLWTVp5US9Znvim+2EcAjbukp1X0GchjTQZg9reXYo1vjbXwMYl/KQhJgLD2sPMJaa7qicyMETf6m5WW+yqYBKtx/MVtq8Q3qMnrzgD14EjD/Klgn0H0/u6dN/9wD1PPXHxA25eE7x988Uwd41LW95n5nCqlbnGJXQPj8aYh5Nus1kWwMLQ=" Mar 09 23:00:07 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:07+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:07 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:07+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:07 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:07+08:00" level=debug msg="completed challenge" Mar 09 23:00:07 dac.x2-nvme go-librespot[16623]: time="2025-03-09T23:00:07+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:07 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:07 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:07 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:09 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:00:09 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:09 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:00:09 dac.x2-nvme volumio[1181]: (node:1181) 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: 1742) Mar 09 23:00:09 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:00:09 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:09 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:10 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:10 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:10 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:10 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:10 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4731. Mar 09 23:00:10 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:10 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:10 dac.x2-nvme go-librespot[16642]: go-librespot daemon starting... Mar 09 23:00:10 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:10+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:10 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:10+08:00" level=debug msg="app state loaded" Mar 09 23:00:10 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:10+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:10 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:10+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=info msg="zeroconf server listening on port 42255" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=debug msg="obtained new client token: AADFgzHWw3gGQMnRg2cLv9yu69p6ybeQCCXlWzKhPlPBpXDBk7G3ZtsR1YZDrXxJVO7sg53guq9a73zUtZ6K0UzQph3ECWiyzLctCDbRAQuJX9urQ4SkOJQWjEnBwDbmmwoEOFBwvvkZexDdVlzOYDUvYJdaHJihho1of+/HnthOf2CXREAfdoaAHvmu7b6L70lVpTWi6QQm4HorF+PvBgrFem+nQO9gXuuwuiOKSDZdVwqaBgJ5ZjsHtQ==" Mar 09 23:00:11 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:11+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:12 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:12+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:12 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:12+08:00" level=debug msg="completed challenge" Mar 09 23:00:12 dac.x2-nvme go-librespot[16642]: time="2025-03-09T23:00:12+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:12 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:12 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:13 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:13 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:14 dac.x2-nvme volumio[1181]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 09 23:00:14 dac.x2-nvme volumio[1181]: info: Retrying Login Due to Network Error Mar 09 23:00:15 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:15 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4732. Mar 09 23:00:15 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:15 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:15 dac.x2-nvme go-librespot[16678]: go-librespot daemon starting... Mar 09 23:00:15 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:15+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:15 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:15+08:00" level=debug msg="app state loaded" Mar 09 23:00:15 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:15+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:15 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:15+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=info msg="zeroconf server listening on port 43739" Mar 09 23:00:16 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=debug msg="new websocket client" Mar 09 23:00:16 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:16 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:16+08:00" level=debug msg="obtained new client token: AADDMYPix/06VWkhEJXiR03UBecscG7oZAWqP8ZHYugtW1j89TtRj7l2uBs1ztd5wC4j8MQ7xS8b5zG2qI7fOWV9pzEXILvpz7FrNxfSr1w9Qd0D/9HJzHrcb4FTJsxOADj0EZtgwVMHolc0PE2gSg31SdR3w1lXD7miDt7kgI8R+U6UaUH4JeOgECg0BYtRKozfNKm9rQU+EniiW8tukhT6TiF9iIPUzQ8m3JmiimpfdKdnoXZxc4LdUA==" Mar 09 23:00:17 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:17+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:17 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:17+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:17 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:17+08:00" level=debug msg="completed challenge" Mar 09 23:00:17 dac.x2-nvme go-librespot[16678]: time="2025-03-09T23:00:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:17 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:17 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:17 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:18 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:19 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:00:19 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:19 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:00:19 dac.x2-nvme volumio[1181]: (node:1181) 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: 1743) Mar 09 23:00:19 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:00:19 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:19 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:20 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:20 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:20 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:20 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4733. Mar 09 23:00:20 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:20 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:20 dac.x2-nvme go-librespot[16697]: go-librespot daemon starting... Mar 09 23:00:20 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:20+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:20 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:20+08:00" level=debug msg="app state loaded" Mar 09 23:00:20 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:20+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:20 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:21 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:21+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:21 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:21+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:21 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:21+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:21 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:21+08:00" level=info msg="zeroconf server listening on port 42159" Mar 09 23:00:21 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:21+08:00" level=debug msg="obtained new client token: AAAql09GE5uzGrerZd2vZCr9q9NvpN8zAniBx3UQRdPnXCFwulxuIGBCjsDIwt3u/uhnfy8r4ecHFPZJ2gMPKmNUHA1HBb1ZGzns09xKFIiJEfmZZWFvnGnlhpW+OGbElwNqhg3qB+YRfxDg1nWFcqkn4k8T2mL9/VeisFYvA7bdZAAppYM+7EWsC8MRpMq6WIinoqC1VlOcYKVkPa/qSi5JHj4amIsqb/kKyWNQj3CQ5ErJ6u90sEhXPA==" Mar 09 23:00:22 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:22+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:22 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:22+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:22 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:22+08:00" level=debug msg="completed challenge" Mar 09 23:00:22 dac.x2-nvme go-librespot[16697]: time="2025-03-09T23:00:22+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:22 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:22 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:23 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:23 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:24 dac.x2-nvme volumio[1181]: info: MyVolumio login type: Token Mar 09 23:00:25 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:25 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4734. Mar 09 23:00:25 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:25 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:25 dac.x2-nvme go-librespot[16732]: go-librespot daemon starting... Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="app state loaded" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:26 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="new websocket client" Mar 09 23:00:26 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:26 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:26+08:00" level=info msg="zeroconf server listening on port 35297" Mar 09 23:00:26 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:27 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:27+08:00" level=debug msg="obtained new client token: AAAsmcnfkwXqj28HiNUtAOVc8MXql0zoKMTpmxqmoLfyRLmAFkoaFLeHrelHEdzkvVugoNvLDb55rTSPZdd4Zxp1pHs5WInNzbZi9cjoAF/HBZ0rKpERS70lQwUQROotGaLtZe3sIGw0VPTxOqzcYGADPXqBp0MzMTTCb85YT+NEo1DKnWvmqlDB+9Qv2d53uTrpTwg9AQePn3edBgCYmOn9Jb5rLoLYcik+KaPuyJbYM2NYBIQxoi4=" Mar 09 23:00:27 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:27+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:27 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:27+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:27 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:27+08:00" level=debug msg="completed challenge" Mar 09 23:00:27 dac.x2-nvme go-librespot[16732]: time="2025-03-09T23:00:27+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:27 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:27 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:27 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:29 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:00:29 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:29 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:00:29 dac.x2-nvme volumio[1181]: (node:1181) 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: 1744) Mar 09 23:00:29 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:00:29 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:29 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:30 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:30 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:30 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:30 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4735. Mar 09 23:00:30 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:30 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:30 dac.x2-nvme go-librespot[16750]: go-librespot daemon starting... Mar 09 23:00:30 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:30+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:30 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:30+08:00" level=debug msg="app state loaded" Mar 09 23:00:30 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:30+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:30 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:30+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:30 dac.x2-nvme volumiologrotate[754]: ls: cannot access '/var/log/samba/log.wb-DAC.X2': No such file or directory Mar 09 23:00:30 dac.x2-nvme volumiologrotate[754]: ls: cannot access 'NVME': No such file or directory Mar 09 23:00:31 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:31+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:31 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:31+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:31 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:31+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:31 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:31+08:00" level=info msg="zeroconf server listening on port 37089" Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: Discovery: Getting this device information Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:32 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 23:00:33 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:33+08:00" level=debug msg="obtained new client token: AADwO/e/oYC5BR5Y9ELOM35z3pnfF00CL7tRAlrahuidjeefUbd5fhNKyt4AomrOgTzLfqXBqo6e5n8tcAblU6Wjz+NIlshCY+snxhyZ4DpY4RWKrkjluR3BjJwulHF3bsKdCgrBZK0z6fl0oOPcFU6NzZcVmqSXOY0KpZQHVj7B8xM2EDThHVQ0CKiKIkN4RMOoDiLv3/pCPTw/wtKsYG0MvtXc9RA4QrhKMMHLp+KNrqRyLTgepjwyTA==" Mar 09 23:00:33 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:33+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:33 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:33+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:33 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:33+08:00" level=debug msg="completed challenge" Mar 09 23:00:33 dac.x2-nvme go-librespot[16750]: time="2025-03-09T23:00:33+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:33 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:33 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:33 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:33 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:34 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:36 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:36 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4736. Mar 09 23:00:36 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:36 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: go-librespot daemon starting... Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:36+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:36+08:00" level=debug msg="app state loaded" Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:36+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:36+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:36 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:36 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:36+08:00" level=debug msg="new websocket client" Mar 09 23:00:36 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:37 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:37+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:37 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:37+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:37 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:37+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:37 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:37+08:00" level=info msg="zeroconf server listening on port 36129" Mar 09 23:00:38 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:38+08:00" level=debug msg="obtained new client token: AABerT1fXKdD7qq8zhYIksBgHzFQ9CT/vwuA/gMNKQylFcwTHY6IY7uceVgJiBgoDU7whCuYR0U9nAYPLjHGh/ph+r9+QVN5B8BD5CzY6qC0g7ZDINEeaG1a+tW6Z3SzgAcelb4hSlPjSQxPnDRsqIi8KnHyC8UaRT75HSEyelVQQv40WK+nH/mBQlARKZhkme14ErY2ybuyhxRptr/ZUj+2tDbmBL49wY01jk9QSCOgMUOXKQns" Mar 09 23:00:38 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:38+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:38 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:38+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:38 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:38+08:00" level=debug msg="completed challenge" Mar 09 23:00:38 dac.x2-nvme go-librespot[16832]: time="2025-03-09T23:00:38+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:38 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:38 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:38 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:39 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:00:39 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:39 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:00:39 dac.x2-nvme volumio[1181]: (node:1181) 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: 1745) Mar 09 23:00:39 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:00:39 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:39 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:41 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:41 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:41 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:41 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4737. Mar 09 23:00:41 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:41 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:41 dac.x2-nvme go-librespot[16851]: go-librespot daemon starting... Mar 09 23:00:41 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:41+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:41 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:41+08:00" level=debug msg="app state loaded" Mar 09 23:00:41 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:41+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:41 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:41+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:42 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:42+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:00:42 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:42+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:00:42 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:42+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:00:42 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:42+08:00" level=info msg="zeroconf server listening on port 35593" Mar 09 23:00:43 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:43 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:43+08:00" level=debug msg="obtained new client token: AADdfFVikGiK5mk5+r8bE9MlzIj9YGttlz7cssmaHw3EtCjBbE2A1zE8Rr6uICKqB80ym5fEfs3GjuS5oXqPj7OkqNCPDeRSdIGdZV93brUFXvXKWYIqo4OR/YGd9LHYuZzNISgYO9Qi4a/Gnm8r5ltGZFlGbkPejCyyf0qHSKJjdRXr+f9fHSysfiPEjFaKV5euXLslFA6SX8dLgd72TObzzwG6N1UX+xQOavb1StYLLno1Ls6P3dAMyg==" Mar 09 23:00:43 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:43+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:43 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:43+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:43 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:43+08:00" level=debug msg="completed challenge" Mar 09 23:00:44 dac.x2-nvme go-librespot[16851]: time="2025-03-09T23:00:44+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:44 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:44 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:44 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:44 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:47 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:47 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4738. Mar 09 23:00:47 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:47 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: go-librespot daemon starting... Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="app state loaded" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:47 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:47 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="new websocket client" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:47 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:47+08:00" level=info msg="zeroconf server listening on port 37033" Mar 09 23:00:48 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:48+08:00" level=debug msg="obtained new client token: AADBubWherQjbNedSSOEylnMUIPzMYczoxSeRx37EJ1nkXjguumThiA9dSwsl9Z29xriKnu0gqChXpeNtGa7wZinN0EqSpiPf4E0tg3ExDBUx5d0lxfoGu4fkjDlYa0BJFr6tBJKSO3yGsizY+K+sat1dz64BmCVphPRSaOFtQ+PyaPuy4n0uCakeVxwVn0S9Qo+BU39EjIlAGlDw0+SJcTC6JgubLbKLI0d2aXcZtbAkoSTZfSBjRo+/g==" Mar 09 23:00:48 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:48+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:48 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:48+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:48 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:48+08:00" level=debug msg="completed challenge" Mar 09 23:00:48 dac.x2-nvme go-librespot[16888]: time="2025-03-09T23:00:48+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:48 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:48 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:48 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:50 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:00:50 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:50 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:00:50 dac.x2-nvme volumio[1181]: (node:1181) 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: 1746) Mar 09 23:00:50 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:00:50 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:00:50 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:00:51 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:00:51 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:51 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:51 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:51 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4739. Mar 09 23:00:51 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:51 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:51 dac.x2-nvme go-librespot[16904]: go-librespot daemon starting... Mar 09 23:00:51 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:51+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:51 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:51+08:00" level=debug msg="app state loaded" Mar 09 23:00:51 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:51+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:51 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:51+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=info msg="zeroconf server listening on port 44181" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=debug msg="obtained new client token: AABbniIDg3s/pi1S4G7/jl4hsWQQAgrOUc1Au+YCdaKTuYL6ITG2ZdMiIt3WwumhSwB62yam1W5SV7xv2d2wwulagUnrp/sLilWD9vbWRpXSeJ1uiN7WH9DlTA5cGEdmMC1hZ1LJj8P80MTxbgh2dOUpUokxIqNumGVZJS/OpfuCb4hGORBSlqZIOXJNkLVN7xObkFInSTwBjOlAxRfUCGIb0OqRNJo2p0PY/5In5fVKOP0Nez/ELPghmw==" Mar 09 23:00:52 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:52+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:53 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:53+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:53 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:53+08:00" level=debug msg="completed challenge" Mar 09 23:00:53 dac.x2-nvme go-librespot[16904]: time="2025-03-09T23:00:53+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:53 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:53 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:54 dac.x2-nvme volumio[1181]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 09 23:00:54 dac.x2-nvme volumio[1181]: info: Retrying Login Due to Network Error Mar 09 23:00:54 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:54 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:00:56 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:00:56 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4740. Mar 09 23:00:56 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:00:56 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: go-librespot daemon starting... Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=debug msg="app state loaded" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=debug msg="stored credentials not found" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:00:56 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:56+08:00" level=info msg="zeroconf server listening on port 45065" Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=debug msg="obtained new client token: AAAzirdurdna30/Tz0Xa02c3oTd2++pNP6yq4ORvvIIvQI8Qq8fGzjZ0yvf3dwhKHxNv4vJPwmzGVu0WasN6+gosjbu3EZKYdnUEpD4d0x2gozw0mUrmOI80EOxMqjxSbuyIoeWT79yv6hmVVs6twMOpziP+CWRVeAxUxoyIUGiBUakGTrcBh8vlPHmAxjnHNOaUo6BDLNLvHD/YGREd0XK3KqvCJA862j0N7Aq7Rx/xfNtt0/SepATVbA==" Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=debug msg="completed keyexchange" Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=debug msg="completed challenge" Mar 09 23:00:57 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=debug msg="new websocket client" Mar 09 23:00:57 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:00:57 dac.x2-nvme go-librespot[16940]: time="2025-03-09T23:00:57+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:00:57 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:00:57 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:00:57 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:00:59 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:01:00 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:01:00 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:00 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:01:00 dac.x2-nvme volumio[1181]: (node:1181) 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: 1747) Mar 09 23:01:00 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:01:00 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:01:00 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:01:00 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:01:00 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:00 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:01:00 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4741. Mar 09 23:01:00 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:01:00 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:01:00 dac.x2-nvme go-librespot[16958]: go-librespot daemon starting... Mar 09 23:01:00 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:00+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:01:00 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:00+08:00" level=debug msg="app state loaded" Mar 09 23:01:00 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:00+08:00" level=debug msg="stored credentials not found" Mar 09 23:01:00 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:00+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:01:01 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:01+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:01:01 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:01+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:01:01 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:01+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:01:01 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:01+08:00" level=info msg="zeroconf server listening on port 36383" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=debug msg="obtained new client token: AABCPwLTJEfwEuy2oKox9FGvWPyqQufPQEHmHay98rDndJf/ofQs3vuZH91FaIBNF76eZvZGKlTTHHZqDrzc8c9/vOxUAyt4X9ZweUMW2mH6yB1Ar8CubHcC5KQ5/3XvXr009q/UmhUH8wvBtESnMqWaqWpGKwyAAl0l1mvnxjgWZCgGCSnikSGHH9S4gFI72uB4ZdHIvky5UOS/KjyocY1tMiVQDppm5TEa+FgPuEOFMQQ1E5748gksIw==" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=debug msg="connected to ap-gae2.spotify.com:443" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=debug msg="completed keyexchange" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=debug msg="completed challenge" Mar 09 23:01:02 dac.x2-nvme go-librespot[16958]: time="2025-03-09T23:01:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:01:02 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:01:02 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: Discovery: Getting this device information Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:01:02 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 23:01:03 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:01:03 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:04 dac.x2-nvme volumio[1181]: info: MyVolumio login type: Token Mar 09 23:01:05 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:01:05 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4742. Mar 09 23:01:05 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:01:05 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:01:05 dac.x2-nvme go-librespot[16994]: go-librespot daemon starting... Mar 09 23:01:05 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:05+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:01:05 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:05+08:00" level=debug msg="app state loaded" Mar 09 23:01:05 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:05+08:00" level=debug msg="stored credentials not found" Mar 09 23:01:05 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=info msg="zeroconf server listening on port 38269" Mar 09 23:01:06 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:01:06 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket established Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="new websocket client" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="obtained new client token: AAA/4NXaDLDTUOOn2P/ZzQdbt0w8KnIhpmz7HxKD31wed7b1N8Ld/JqXoRbxiQ/bNnOOBR+MkOet2QVc2GfKB3RXc7GOp8j9dn0I+AW1owyxmbaipC83bBy3qJqrTnLmlLssilVpzGoXn0mPzLZw1LnJ1AEfQYcd2aJc9EyinoumHT5czbon6K/NrLUOGxF8JzgfmhL6xAarcKPUyeJt+CIkDsFCr46oyzhddxOVNXjZpbIayNZ1pux+9g==" Mar 09 23:01:06 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:06+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:01:07 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:07+08:00" level=debug msg="completed keyexchange" Mar 09 23:01:07 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:07+08:00" level=debug msg="completed challenge" Mar 09 23:01:07 dac.x2-nvme go-librespot[16994]: time="2025-03-09T23:01:07+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:01:07 dac.x2-nvme volumio[1181]: info: Connection to go-librespot Websocket closed Mar 09 23:01:07 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:01:07 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:01:07 dac.x2-nvme wpa_supplicant[1069]: wlan0: Failed to initiate sched scan Mar 09 23:01:09 dac.x2-nvme volumio[1181]: info: Getting Spotify volume Mar 09 23:01:09 dac.x2-nvme volumio[1181]: (node:1181) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:09 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 09 23:01:09 dac.x2-nvme volumio[1181]: (node:1181) 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: 1748) Mar 09 23:01:09 dac.x2-nvme volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 09 23:01:09 dac.x2-nvme volumio[1181]: info: CoreCommandRouter::volumioGetState Mar 09 23:01:09 dac.x2-nvme volumio[1181]: info: CorePlayQueue::getTrack 0 Mar 09 23:01:10 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:01:10 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:10 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 23:01:10 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4743. Mar 09 23:01:10 dac.x2-nvme systemd[1]: Stopped go-librespot Daemon. Mar 09 23:01:10 dac.x2-nvme systemd[1]: Started go-librespot Daemon. Mar 09 23:01:10 dac.x2-nvme go-librespot[17011]: go-librespot daemon starting... Mar 09 23:01:10 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:10+08:00" level=info msg="running go-librespot 0.2.0" Mar 09 23:01:10 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:10+08:00" level=debug msg="app state loaded" Mar 09 23:01:10 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:10+08:00" level=debug msg="stored credentials not found" Mar 09 23:01:10 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:10+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 09 23:01:11 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:11+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 09 23:01:11 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:11+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 09 23:01:11 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:11+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 09 23:01:11 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:11+08:00" level=info msg="zeroconf server listening on port 34967" Mar 09 23:01:12 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:12+08:00" level=debug msg="obtained new client token: AADebJjg8qgkwx5LLbFoEeoICASc4PZGQvsFkQ7N1n8gM35VR1LjtZv48/TwL5VwqqfNec2yrlmUlLEhD1GI4UtcqBmeIzBDDLO8QGScnyWd2QV2zcCjhJ6kYCNWebC2h4hXithrZ3dX0UofXLjGNsu8LolouB4C0UL1IDtUWu8rDZ+FLywK1KOnkXDayBTFrbu75nc0nY8KyemGz+6DCNrXo9uazYN2XA8sZDREWMUW5Uur/uzAgXBKHQ==" Mar 09 23:01:12 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:12+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 09 23:01:12 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:12+08:00" level=debug msg="completed keyexchange" Mar 09 23:01:12 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:12+08:00" level=debug msg="completed challenge" Mar 09 23:01:12 dac.x2-nvme go-librespot[17011]: time="2025-03-09T23:01:12+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 09 23:01:12 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 23:01:12 dac.x2-nvme systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 23:01:13 dac.x2-nvme volumio[1181]: info: Initializing connection to go-librespot Websocket Mar 09 23:01:13 dac.x2-nvme volumio[1181]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 09 23:01:14 dac.x2-nvme volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 23:01:14 dac.x2-nvme volumio[1181]: Error: connect ETIMEDOUT 108.160.170.44:80 Mar 09 23:01:14 dac.x2-nvme volumio[1181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 09 23:01:14 dac.x2-nvme volumio[1181]: errno: -110, Mar 09 23:01:14 dac.x2-nvme volumio[1181]: code: 'ETIMEDOUT', Mar 09 23:01:14 dac.x2-nvme volumio[1181]: syscall: 'connect', Mar 09 23:01:14 dac.x2-nvme volumio[1181]: address: '108.160.170.44', Mar 09 23:01:14 dac.x2-nvme volumio[1181]: port: 80 Mar 09 23:01:14 dac.x2-nvme volumio[1181]: } Mar 09 23:01:14 dac.x2-nvme volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 23:01:14 dac.x2-nvme sudo[17039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 23:00 Mar 09 23:01:14 dac.x2-nvme sudo[17039]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"