-- Logs begin at Sat 2024-04-20 07:25:22 UTC, end at Sat 2024-04-20 09:50:37 UTC. -- Apr 20 09:49:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 797. Apr 20 09:49:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:02 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:02 volumio go-librespot[17556]: Librespot-go daemon starting... Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=info msg="generated new device id: c9d7e6d8e1f96f6717ba9e9793dca24d9934e539" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=debug msg="obtained new client token: AABLr6O1UrxT8nAaxwHPfyn/vRyxNJkQ8yI4YX2ZaQY5zUtKAIzp/vRxQ03acBNf4A5iMRFND4md6FF2YJ14/6N0pqgaQSe1BB0ECUhrDOuqdO/9T+Altm7NWHfTDs4IQMtldqjpZOI7CWRmEa4TZHxuhvcKtgnaUO+pd0LZKNi2Gh7GrDSngPAqAtjp6haBsWvsPEaaffb6zoS+AEer/enWMJ2Q57mnZJjxuQopmZeOkXGpHPmmeLHYawzI" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" 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]" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=debug msg="completed keyexchange" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=debug msg="completed challenge" Apr 20 09:49:02 volumio go-librespot[17556]: time="2024-04-20T09:49:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:02 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:02 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:05 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 798. Apr 20 09:49:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:05 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:06 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:06 volumio go-librespot[17565]: Librespot-go daemon starting... Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=info msg="generated new device id: 0822005d61f32572524a2e3df48acdda382c852f" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=debug msg="obtained new client token: AADUDT9n6NBPg/98WEpufBjSEKpOtfLqcy0QBaFwhfO6bheYR3V9eyXkr31HK0I6ywzq7GE9kzlv3deVC2PTyqyc21rPXtdBe1VtOC6ckFpxFsIgwndZMQY/FlQU7PE13h9WHbWMLOxZzPwGyMGPim9Lfhvkpx9n6hTh9nODgL3axW7waTW6Aj8QvdJFT3iSwYB66ps4f690uI7kgGjeVorrMpegJc3NQgZAEF9o8Hi19W6FgK3t4gRN9QGZ" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" 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]" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=debug msg="completed keyexchange" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=debug msg="completed challenge" Apr 20 09:49:06 volumio go-librespot[17565]: time="2024-04-20T09:49:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:08 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:08 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 799. Apr 20 09:49:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:10 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:10 volumio go-librespot[17575]: Librespot-go daemon starting... Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=info msg="generated new device id: 369e10b7eea47dd97c29b64ffad164708f976d97" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=debug msg="obtained new client token: AAA7jfqhDHshsY09BlGOmWNg2cjo38k6966QTOyKL+8A66LYZ2E7BOt4bcNpKLQ3Tlxb/OukjkcSRdL8De7DiOmSSVikhuoTSnIRhUyZ/8vOnRngLHsfJSG6ObbpyZWCfmkp3ELZuY1TvtqFC7z2Sf3/O2Rsk6c1qynxDQLw0Av7J6WJDyMxyE6HWrQ9nzVG126/+BXXPmVxeiim9jvwEDXeJ2/AIr+n6lEI26XDbqQSi+pV+tdT5ciyikEx" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" 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]" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=debug msg="completed keyexchange" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=debug msg="completed challenge" Apr 20 09:49:10 volumio go-librespot[17575]: time="2024-04-20T09:49:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:11 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:11 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 800. Apr 20 09:49:13 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:14 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:14 volumio go-librespot[17583]: Librespot-go daemon starting... Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=info msg="generated new device id: 10a2d1d55c83f9c3d5924c6295cc9e723cc50595" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=debug msg="obtained new client token: AABhNxOnlobleSr92PH7Z4/YyY1/iQrkDaTBL/nN7V+T1eVEv8y4/lAkt07RcPbk1qGvQoiS9BV+PzLHmk4dzAKAXE/uXEPTfi32UfE2nRK5YiX9ZtdPZPoYFOoYNZfIlYSDJB3zHLr98+CnBzbclpSiiPqlNzVj+qeUIA+QpiV88Wf4k5k5PYRGxb6vR2Oawf/S83n1EOk9JsyNLcgHl2U3iWlztbProBGToFn5LYsIVb2AJGpb9VaD13XM" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" 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]" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=debug msg="completed keyexchange" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=debug msg="completed challenge" Apr 20 09:49:14 volumio go-librespot[17583]: time="2024-04-20T09:49:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:14 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:14 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 801. Apr 20 09:49:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:17 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:18 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:18 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:18 volumio go-librespot[17592]: Librespot-go daemon starting... Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=info msg="generated new device id: 4b20c78417901c173579b7061546a475b6b8322a" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=debug msg="obtained new client token: AAB2MH5EJuA3UQScsWry9DETksIYwmdJRGaCvmWPjIoBepe0bhG3gyhOVa9hWHwbilFqG3B7tYHhfKAOJ8n2AZK3D0GCaNv9Z5mHD1Nj3/GQmp3srDdYW7kXJJqnMlgVwmyvushJ69q/NVyddhDiauzN9KTXO9hdQpNTKaZVc5Fq7k6wVTlPGZEZM+okvmcaU/WzqboxRLfHsqKkHmrZfNj4YzPRvPvc+PUWwu8sHbqbZYkeZoaBwLC3wmsF" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" 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]" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=debug msg="completed keyexchange" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=debug msg="completed challenge" Apr 20 09:49:18 volumio go-librespot[17592]: time="2024-04-20T09:49:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:20 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:20 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 802. Apr 20 09:49:21 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:22 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:22 volumio go-librespot[17600]: Librespot-go daemon starting... Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" level=info msg="generated new device id: 84cfc49e397c0d62bd109d1720b79f5443074d4f" Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" level=debug msg="obtained new client token: AAA5iS5AYyd4Ac9kahMdQSAnWdMiHOCTtXMdLBcuexqQXhAc0y1hhkPCdb2VRPDXeknSd5qBNhaoJLXdn0+CRpybbZKck4ZKAvEh1N+0117/C9BzDehwgUZKbLRj+Vz/E1bWj9CXuAYnDIBT0+HRGN9P3zyAOSk3iIb3V2vScbGJZEqhRyHMlGO+WQUJc8TaN2MKnwk4eT3WxeKDaE3D4Jij9hyEFtUbF03v2yxMTyFMvXwdeCsytG+eu1Qk" Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" 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]" Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" level=debug msg="completed keyexchange" Apr 20 09:49:22 volumio go-librespot[17600]: time="2024-04-20T09:49:22Z" level=debug msg="completed challenge" Apr 20 09:49:23 volumio go-librespot[17600]: time="2024-04-20T09:49:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:23 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:23 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 803. Apr 20 09:49:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:26 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:26 volumio go-librespot[17648]: Librespot-go daemon starting... Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" level=info msg="generated new device id: 090fe9fbe2697db5c941f8007300cc8f7c1ff6df" Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" level=debug msg="obtained new client token: AADskxumn+I6MKlwXBX7faWzHTSCxKK/PckD3ey99dDewAjSB9oiDK9Czsi/LPPnLrSAzMcX3VQObhQsJ1DMYnbjUMuiZ/uoDPG/+XA2Q4WS5rMTBexIlR6F0Euw+uVVV4frSNbqforx7eOxmrWRlsv58M6r7NHxrCI2IgB7RNPN3lPXbQjDTFajhs3zJYdfng4YAjrrb29PNqRX8Buk9YsO73ksKeVS4eS/Drkd0SqSbqQwXDXs90wQqnt9Zns=" Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" 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]" Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" level=debug msg="completed keyexchange" Apr 20 09:49:26 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:26 volumio go-librespot[17648]: time="2024-04-20T09:49:26Z" level=debug msg="new websocket client" Apr 20 09:49:26 volumio volumio[7305]: info: Connection to go-librespot Websocket established Apr 20 09:49:27 volumio go-librespot[17648]: time="2024-04-20T09:49:27Z" level=debug msg="completed challenge" Apr 20 09:49:27 volumio go-librespot[17648]: time="2024-04-20T09:49:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:27 volumio volumio[7305]: info: Connection to go-librespot Websocket closed Apr 20 09:49:29 volumio volumio[7305]: info: Getting Spotify volume Apr 20 09:49:30 volumio volumio[7305]: (node:7305) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:30 volumio volumio[7305]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 20 09:49:30 volumio volumio[7305]: (node:7305) 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: 131) Apr 20 09:49:30 volumio volumio[7305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Apr 20 09:49:30 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:30 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:30 volumio volumio[7305]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Apr 20 09:49:30 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:30 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 804. Apr 20 09:49:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:30 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:30 volumio go-librespot[17656]: Librespot-go daemon starting... Apr 20 09:49:30 volumio go-librespot[17656]: time="2024-04-20T09:49:30Z" level=info msg="generated new device id: 9c07ddba3f60c58fb6c06ba74a1aaa9b1d4a9e5f" Apr 20 09:49:30 volumio go-librespot[17656]: time="2024-04-20T09:49:30Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:30 volumio go-librespot[17656]: time="2024-04-20T09:49:30Z" level=debug msg="obtained new client token: AABX5TLbxNZrkJxEuCRNQohLmZwkrYnWq8glnhF1yNX48nQJpxw7jdWka64SwwNP4YCEO3BR7ojbZQjqISKAum/7i6RpvR1qztt+yAltMlqMBwzKBi0Q4ozVcXZPk4plfTCL55eq28q2kVBSx3sCM1OOD0tEU63OcqUPAawKduzW37qUMrmIP//kaZh3iGGptue9+LS5Ub5gMTjmyrwsbyMFhX3afFySyJ5F2kTnQ0BYGgbfTHCKWL9mutwX4uA=" Apr 20 09:49:30 volumio go-librespot[17656]: time="2024-04-20T09:49:30Z" 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]" Apr 20 09:49:30 volumio go-librespot[17656]: time="2024-04-20T09:49:30Z" level=debug msg="completed keyexchange" Apr 20 09:49:31 volumio go-librespot[17656]: time="2024-04-20T09:49:31Z" level=debug msg="completed challenge" Apr 20 09:49:31 volumio go-librespot[17656]: time="2024-04-20T09:49:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:32 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:32 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:32 volumio volumio[7305]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: unknown Total Clients: 5 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:32 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:32 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:32 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 09:49:32 volumio volumio[7305]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: unknown Total Clients: 6 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:32 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 09:49:32 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 09:49:33 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:33 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 805. Apr 20 09:49:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:34 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:34 volumio go-librespot[17664]: Librespot-go daemon starting... Apr 20 09:49:34 volumio go-librespot[17664]: time="2024-04-20T09:49:34Z" level=info msg="generated new device id: ad505615f0d1a7d87de57d2d0de8f4bb9b708d2e" Apr 20 09:49:34 volumio go-librespot[17664]: time="2024-04-20T09:49:34Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:34 volumio go-librespot[17664]: time="2024-04-20T09:49:34Z" level=debug msg="obtained new client token: AADXD8bP/U9hJbQWxt66IE20OLwn3jVrMJjAgUnyzm23/kkTUUbRuVAl/58kaCsMtYJHIL2VdUVS9sup2eiJoYB/QPyp8h2L+w7vI6aGjxZ1FOiVxb+ozGw+0FS1pjz2PVQ/z2xewdaovAWfC+gQBKi/2PLDRCm1iEk4FDA2N3vu3TjF4QA+Z8L9cPJ/vi4twOn08y1R4DFO7PWsFTrCcTYeYqWyxR1AYwhbwUrwJ3mLZwC1g+iMrwAfwbsvf74=" Apr 20 09:49:34 volumio go-librespot[17664]: time="2024-04-20T09:49:34Z" 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]" Apr 20 09:49:34 volumio go-librespot[17664]: time="2024-04-20T09:49:34Z" level=debug msg="completed keyexchange" Apr 20 09:49:34 volumio sudo[17676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:49:34 volumio sudo[17673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 09:49:34 volumio sudo[17676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:49:34 volumio sudo[17673]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:49:34 volumio sudo[17673]: pam_unix(sudo:session): session closed for user root Apr 20 09:49:34 volumio sudo[17676]: pam_unix(sudo:session): session closed for user root Apr 20 09:49:34 volumio volumio[7305]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:35 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 09:49:35 volumio volumio[7305]: info: Received Get System Info Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:35 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:35 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:35 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:35 volumio go-librespot[17664]: time="2024-04-20T09:49:35Z" level=debug msg="completed challenge" Apr 20 09:49:35 volumio volumio[7305]: info: Listing playlists Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 09:49:35 volumio go-librespot[17664]: time="2024-04-20T09:49:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:35 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 20 09:49:36 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:36 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:36 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 09:49:36 volumio volumio[7305]: info: Received Get System Info Apr 20 09:49:36 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:36 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:36 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:36 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:36 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:36 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::volumioGetQueue Apr 20 09:49:37 volumio volumio[7305]: info: CoreStateMachine::getQueue Apr 20 09:49:37 volumio volumio[7305]: info: CorePlayQueue::getQueue Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 09:49:37 volumio volumio[7305]: info: Received Get System Info Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:37 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:37 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:37 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 806. Apr 20 09:49:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:38 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:38 volumio go-librespot[17681]: Librespot-go daemon starting... Apr 20 09:49:38 volumio go-librespot[17681]: time="2024-04-20T09:49:38Z" level=info msg="generated new device id: 4b1a287688acc562e2204c922fc918eea6be9c87" Apr 20 09:49:38 volumio go-librespot[17681]: time="2024-04-20T09:49:38Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:38 volumio go-librespot[17681]: time="2024-04-20T09:49:38Z" level=debug msg="obtained new client token: AACH2fwJa1CUb6LwAhbp+SJZFPAgEokfhKSsVxio7v49CqYREV+vtvAWyZlFURYEa+eRfM3S1PyT2/+WxvZQ3Ekfs0ss4wLZWk1Yev4rnk30inHeAgrVRl9BAD9g4e/ZZObHCySFa8jyhHgsvOx3JCj4pc+VL0UV38z5tE4iXc37GIH8xENXundX3zCNCDXX+ksEiSzX+oESG69DlQOm/CMIsiPD9kLncp93mPRJ9XV31DzOTDpswz1IE/BMlaY=" Apr 20 09:49:38 volumio go-librespot[17681]: time="2024-04-20T09:49:38Z" 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]" Apr 20 09:49:38 volumio go-librespot[17681]: time="2024-04-20T09:49:38Z" level=debug msg="completed keyexchange" Apr 20 09:49:39 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:39 volumio go-librespot[17681]: time="2024-04-20T09:49:39Z" level=debug msg="new websocket client" Apr 20 09:49:39 volumio volumio[7305]: info: Connection to go-librespot Websocket established Apr 20 09:49:39 volumio go-librespot[17681]: time="2024-04-20T09:49:39Z" level=debug msg="completed challenge" Apr 20 09:49:39 volumio go-librespot[17681]: time="2024-04-20T09:49:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:39 volumio volumio[7305]: info: Connection to go-librespot Websocket closed Apr 20 09:49:42 volumio volumio[7305]: info: Getting Spotify volume Apr 20 09:49:42 volumio volumio[7305]: (node:7305) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:42 volumio volumio[7305]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 20 09:49:42 volumio volumio[7305]: (node:7305) 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: 132) Apr 20 09:49:42 volumio volumio[7305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 20 09:49:42 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:42 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:42 volumio volumio[7305]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Apr 20 09:49:42 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:42 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 807. Apr 20 09:49:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:42 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:42 volumio go-librespot[17689]: Librespot-go daemon starting... Apr 20 09:49:42 volumio go-librespot[17689]: time="2024-04-20T09:49:42Z" level=info msg="generated new device id: ee9dfc78e81e0bd7c20aed9eb1d0f46b0594e9c9" Apr 20 09:49:42 volumio go-librespot[17689]: time="2024-04-20T09:49:42Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:42 volumio go-librespot[17689]: time="2024-04-20T09:49:42Z" level=debug msg="obtained new client token: AACJFgtmvDQArGJfL/QBhZdW9KZXd/+XgRCkUGd1GeWhRKCjHUFL7EBZh8gKiJoQKjcPcurPIN/GKqlMBmdKgAJ7SC77u1YsIABrOFjN1gAadERGCvn4rmYHMa7yT0pDg4Z/2+FvklsrzC9b1WtjFtcD5+cwUIbt+oM6KgJFqjo6+hnbES18T2/wBYohKmTOLACEYZ/5ulw9kMXW3ENuLYS0+N+8GwQZVxm0ncwkbOCyjt+fVxBCz9mF4DSSyKg=" Apr 20 09:49:42 volumio go-librespot[17689]: time="2024-04-20T09:49:42Z" 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]" Apr 20 09:49:42 volumio go-librespot[17689]: time="2024-04-20T09:49:42Z" level=debug msg="completed keyexchange" Apr 20 09:49:43 volumio go-librespot[17689]: time="2024-04-20T09:49:43Z" level=debug msg="completed challenge" Apr 20 09:49:43 volumio go-librespot[17689]: time="2024-04-20T09:49:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:45 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:45 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 808. Apr 20 09:49:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:46 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:46 volumio go-librespot[17698]: Librespot-go daemon starting... Apr 20 09:49:46 volumio go-librespot[17698]: time="2024-04-20T09:49:46Z" level=info msg="generated new device id: 2885651773d388c427d85a0bfee63ce8bcbd07d2" Apr 20 09:49:46 volumio go-librespot[17698]: time="2024-04-20T09:49:46Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:47 volumio go-librespot[17698]: time="2024-04-20T09:49:47Z" level=debug msg="obtained new client token: AAD6BA8LkAosuciLCO8uepjAkPYm+GlSUyjWVcSnpn+zuHyGasUqnPth4CqWxCbBKUm5jzM9LzTopR8kg2as2dvVJ9gDHsUJeQx4oikKODqv/uoIbR3XwhV08vo9qbI/ML7B21gkYtOfpem2YXFMm8vJ6kKSasLFZve6FWSq+Uv623BlGnMHhx+GGpf2biOZXGCa0bomOLiQ+LxOZmKQwdp2+5P1lqiMECCSyd9xxXdpl7PkDcIvN3kBcLw4xSo=" Apr 20 09:49:47 volumio go-librespot[17698]: time="2024-04-20T09:49:47Z" 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]" Apr 20 09:49:47 volumio go-librespot[17698]: time="2024-04-20T09:49:47Z" level=debug msg="completed keyexchange" Apr 20 09:49:47 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:49:47 volumio volumio[7305]: info: CURURI: music-library Apr 20 09:49:47 volumio volumio[7305]: info: Preload queue cleared Apr 20 09:49:47 volumio go-librespot[17698]: time="2024-04-20T09:49:47Z" level=debug msg="completed challenge" Apr 20 09:49:47 volumio go-librespot[17698]: time="2024-04-20T09:49:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:48 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:48 volumio volumio[7305]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 09:49:49 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:49:49 volumio volumio[7305]: info: CURURI: music-library/USB Apr 20 09:49:49 volumio volumio[7305]: info: Preload queue cleared Apr 20 09:49:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 809. Apr 20 09:49:50 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:50 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:50 volumio go-librespot[17706]: Librespot-go daemon starting... Apr 20 09:49:50 volumio go-librespot[17706]: time="2024-04-20T09:49:50Z" level=info msg="generated new device id: d14737cd187fb2e8485fe88d6ad68d38a7b0149d" Apr 20 09:49:50 volumio go-librespot[17706]: time="2024-04-20T09:49:50Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:50 volumio go-librespot[17706]: time="2024-04-20T09:49:50Z" level=debug msg="obtained new client token: AACpxvRV5MUbEIBUm4GF8j2KRqfbFxP3TiyWWdETrGumz8hlSd0LOXVHn6pWkfFeCLpiRcAehsrW20TISG8ibxjYdNIgOGeNGYcTMt6mYQdJBzA52eg3Li2O1/G9NVPLWPRwFYd4dOHNxFh2fn+vcBKflsLQNzpl78+U21aSdXoXr+FFnei6u9K5tXK9GHd2KBW2jcd9T2GSATLvYj/Hoq88ohC2Q+sdQvrfDN29C0ZdO+leY6czYUaleOpTSKo=" Apr 20 09:49:51 volumio go-librespot[17706]: time="2024-04-20T09:49:51Z" 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]" Apr 20 09:49:51 volumio go-librespot[17706]: time="2024-04-20T09:49:51Z" level=debug msg="completed keyexchange" Apr 20 09:49:51 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:51 volumio go-librespot[17706]: time="2024-04-20T09:49:51Z" level=debug msg="new websocket client" Apr 20 09:49:51 volumio volumio[7305]: info: Connection to go-librespot Websocket established Apr 20 09:49:51 volumio go-librespot[17706]: time="2024-04-20T09:49:51Z" level=debug msg="completed challenge" Apr 20 09:49:51 volumio go-librespot[17706]: time="2024-04-20T09:49:51Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:51 volumio volumio[7305]: info: Connection to go-librespot Websocket closed Apr 20 09:49:52 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 09:49:52 volumio volumio[7305]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 09:49:52 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 09:49:53 volumio volumio[7305]: info: Received Get System Version Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 09:49:53 volumio volumio[7305]: info: Received Get System Info Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:49:53 volumio volumio[7305]: info: Discovery: Getting this device information Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::volumioGetState Apr 20 09:49:53 volumio volumio[7305]: info: CorePlayQueue::getTrack 10 Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:49:53 volumio volumio[7305]: info: Preload queue cleared Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 20 09:49:53 volumio volumio[7305]: info: CoreStateMachine::ClearQueue Apr 20 09:49:53 volumio volumio[7305]: info: CoreStateMachine::stop Apr 20 09:49:53 volumio volumio[7305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:49:53 volumio volumio[7305]: info: CorePlayQueue::clearPlayQueue Apr 20 09:49:53 volumio volumio[7305]: info: CorePlayQueue::saveQueue Apr 20 09:49:53 volumio volumio[7305]: info: CoreCommandRouter::volumioPushQueue Apr 20 09:49:53 volumio volumio[7305]: info: CoreStateMachine::addQueueItems Apr 20 09:49:53 volumio volumio[7305]: info: CorePlayQueue::addQueueItems Apr 20 09:49:53 volumio volumio[7305]: info: Preload queue cleared Apr 20 09:49:53 volumio volumio[7305]: info: Adding Item to queue: music-library/USB/MUSICS Apr 20 09:49:53 volumio volumio[7305]: info: Exploding uri music-library/USB/MUSICS in service mpd Apr 20 09:49:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 810. Apr 20 09:49:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:54 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:54 volumio go-librespot[17714]: Librespot-go daemon starting... Apr 20 09:49:54 volumio go-librespot[17714]: time="2024-04-20T09:49:54Z" level=info msg="generated new device id: fe6c019f5e93f9ea62bc7cf5a669fc55f0d42750" Apr 20 09:49:54 volumio go-librespot[17714]: time="2024-04-20T09:49:54Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:55 volumio go-librespot[17714]: time="2024-04-20T09:49:55Z" level=debug msg="obtained new client token: AADB5MyLTd3exlW+vjfzMf+T4wwcClmLJBDikeRWDtwepz+5YN7yZ5Rg6zZt5vGQbfkatWDEOXq8eBORvh6NG8ARcl4S8JamuCMyJZgBz1lRNy4TZm5ZncIjzTx7UCxGPFFKCbGaNO8KafAhrzAIX0MRYtb5Dhyx58YY8gFZXVGJRJDduppxWYsaPVta3jQmDVa4CKzIR8V3HPn6vV0xHsU96XTbD1YGFV6qnXPhKgxn41v58HzJ/ypSU/Q+" Apr 20 09:49:55 volumio go-librespot[17714]: time="2024-04-20T09:49:55Z" 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]" Apr 20 09:49:55 volumio go-librespot[17714]: time="2024-04-20T09:49:55Z" level=debug msg="completed keyexchange" Apr 20 09:49:55 volumio volumio[7305]: info: Getting Spotify volume Apr 20 09:49:55 volumio volumio[7305]: info: Initializing connection to go-librespot Websocket Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics%20Vol.%202/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20Vol.%202%20%5B40845134%5D%20%5B1993%5D%2F01%20-%20The%20Flirts%20-%20Danger.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics Vol. 2 [40845134] [1993]/01 - The Flirts - Danger.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics%20Vol.%202/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20Vol.%202%20%5B40845134%5D%20%5B1993%5D%2F02%20-%20The%20Flirts%20-%20Danger%20(Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics Vol. 2 [40845134] [1993]/02 - The Flirts - Danger (Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics%20Vol.%202/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20Vol.%202%20%5B40845134%5D%20%5B1993%5D%2F03%20-%20The%20Flirts%20-%20Passion%20(Long%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics Vol. 2 [40845134] [1993]/03 - The Flirts - Passion (Long Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics%20Vol.%202/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20Vol.%202%20%5B40845134%5D%20%5B1993%5D%2F04%20-%20The%20Flirts%20-%20Calling%20All%20Boys%20(Long%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics Vol. 2 [40845134] [1993]/04 - The Flirts - Calling All Boys (Long Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Jeanette%20%22Lady%22%20Day/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24207336%5D%20%5B1993%5D%2F01%20-%20Jeanette%20-Lady-%20Day%20-%20Come%20Let%20Me%20Love%20You%20(Vocal).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24207336] [1993]/01 - Jeanette -Lady- Day - Come Let Me Love You (Vocal).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Jeanette%20%22Lady%22%20Day/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24207336%5D%20%5B1993%5D%2F02%20-%20Jeanette%20-Lady-%20Day%20-%20Come%20Let%20Me%20Love%20You%20(Instrumental).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24207336] [1993]/02 - Jeanette -Lady- Day - Come Let Me Love You (Instrumental).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Jeanette%20%22Lady%22%20Day/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24207336%5D%20%5B1993%5D%2F03%20-%20Jeanette%20-Lady-%20Day%20-%20Sexy%20Sexy%20Sexy%20(Slow%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24207336] [1993]/03 - Jeanette -Lady- Day - Sexy Sexy Sexy (Slow Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Jeanette%20%22Lady%22%20Day/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24207336%5D%20%5B1993%5D%2F04%20-%20Jeanette%20-Lady-%20Day%20-%20Sexy%20Sexy%20Sexy%20(Fast%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24207336] [1993]/04 - Jeanette -Lady- Day - Sexy Sexy Sexy (Fast Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=France%20Joli/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24208036%5D%20%5B1993%5D%2F01%20-%20France%20Joli%20-%20The%20Heart%20to%20Break%20the%20Heart.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24208036] [1993]/01 - France Joli - The Heart to Break the Heart.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=France%20Joli/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B24208036%5D%20%5B1993%5D%2F02%20-%20France%20Joli%20-%20Feel%20Like%20Dancing.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [24208036] [1993]/02 - France Joli - Feel Like Dancing.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B40844392%5D%20%5B1993%5D%2F01%20-%20The%20Flirts%20-%20Passion%20(12%20Inch%20Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [40844392] [1993]/01 - The Flirts - Passion (12 Inch Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B40844392%5D%20%5B1993%5D%2F02%20-%20The%20Flirts%20-%20Passion%20(Dub%20Mix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [40844392] [1993]/02 - The Flirts - Passion (Dub Mix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B40844392%5D%20%5B1993%5D%2F03%20-%20The%20Flirts%20-%20Danger%20(Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [40844392] [1993]/03 - The Flirts - Danger (Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B40844392%5D%20%5B1993%5D%2F04%20-%20The%20Flirts%20-%20Helpless%20(You%20Took%20My%20Love).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [40844392] [1993]/04 - The Flirts - Helpless (You Took My Love).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=The%20Flirts/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B40844392%5D%20%5B1993%5D%2F05%20-%20The%20Flirts%20-%20Jukebox%20(Don_t%20Put%20Another%20Dime).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [40844392] [1993]/05 - The Flirts - Jukebox (Don_t Put Another Dime).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Dynasty/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B85619795%5D%20%5B1993%5D%2F01%20-%20Dynasty%20-%20I_ve%20Just%20Begun%20to%20Love%20You.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [85619795] [1993]/01 - Dynasty - I_ve Just Begun to Love You.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Dynasty/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B85619795%5D%20%5B1993%5D%2F02%20-%20Dynasty%20-%20Do%20Me%20Right.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [85619795] [1993]/02 - Dynasty - Do Me Right.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Dynasty/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B85619798%5D%20%5B1993%5D%2F01%20-%20Dynasty%20-%20Satisfied.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [85619798] [1993]/01 - Dynasty - Satisfied.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Dynasty/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B85619798%5D%20%5B1993%5D%2F02%20-%20Dynasty%20-%20I%20Don_t%20Want%20to%20Be%20a%20Freak%20(But%20I%20Can_t%20Help%20Mysel.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [85619798] [1993]/02 - Dynasty - I Don_t Want to Be a Freak (But I Can_t Help Mysel.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Dynasty/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B85619798%5D%20%5B1993%5D%2F03%20-%20Dynasty%20-%20Your%20Piece%20of%20the%20Rock.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [85619798] [1993]/03 - Dynasty - Your Piece of the Rock.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Kat%20Mandu/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B86505009%5D%20%5B1993%5D%2F01%20-%20Kat%20Mandu%20-%20The%20Break%20(85_).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [86505009] [1993]/01 - Kat Mandu - The Break (85_).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Kat%20Mandu/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B86505009%5D%20%5B1993%5D%2F02%20-%20Kat%20Mandu%20-%20The%20Break%20(85_)%20(Mix%202).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [86505009] [1993]/02 - Kat Mandu - The Break (85_) (Mix 2).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Kat%20Mandu/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B86505009%5D%20%5B1993%5D%2F03%20-%20Kat%20Mandu%20-%20The%20Break%20(Original).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [86505009] [1993]/03 - Kat Mandu - The Break (Original).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Kat%20Mandu/12%20Inch%20Classics/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics%20%5B86505009%5D%20%5B1993%5D%2F04%20-%20Kat%20Mandu%20-%20The%20Break%20(Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics [86505009] [1993]/04 - Kat Mandu - The Break (Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=France%20Joli/12%20Inch%20Classics%3A%20Gonna%20Get%20Over%20You/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics-%20Gonna%20Get%20Over%20You%20%5B24207692%5D%20%5B1993%5D%2F01%20-%20France%20Joli%20-%20Gonna%20Get%20Over%20You.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics- Gonna Get Over You [24207692] [1993]/01 - France Joli - Gonna Get Over You.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=France%20Joli/12%20Inch%20Classics%3A%20Gonna%20Get%20Over%20You/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics-%20Gonna%20Get%20Over%20You%20%5B24207692%5D%20%5B1993%5D%2F02%20-%20France%20Joli%20-%20Te%20Olvidare%20(Gonna%20Get%20Over%20You).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics- Gonna Get Over You [24207692] [1993]/02 - France Joli - Te Olvidare (Gonna Get Over You).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=France%20Joli/12%20Inch%20Classics%3A%20Gonna%20Get%20Over%20You/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics-%20Gonna%20Get%20Over%20You%20%5B24207692%5D%20%5B1993%5D%2F03%20-%20France%20Joli%20-%20I%20Wanna%20Take%20a%20Chance%20On%20Love%20(Mastermix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics- Gonna Get Over You [24207692] [1993]/03 - France Joli - I Wanna Take a Chance On Love (Mastermix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Destination/12%20Inch%20Classics%3A%20Move%20On%20Up%20%2F%20My%20%231%20Request/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics-%20Move%20On%20Up%20-%20My%20%231%20Request%20%5B22805662%5D%20%5B1993%5D%2F01%20-%20Destination%20-%20Move%20On%20Up.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics- Move On Up - My #1 Request [22805662] [1993]/01 - Destination - Move On Up.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Destination/12%20Inch%20Classics%3A%20Move%20On%20Up%20%2F%20My%20%231%20Request/large&path=%2Fmnt%2FUSB%2FMUSICS%2F12%20Inch%20Classics-%20Move%20On%20Up%20-%20My%20%231%20Request%20%5B22805662%5D%20%5B1993%5D%2F02%20-%20Destination%20-%20My%20%231%20Request%20(Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/12 Inch Classics- Move On Up - My #1 Request [22805662] [1993]/02 - Destination - My #1 Request (Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F01%20Boogie%20Oogie%20Oogie%20%5BRadio%20Version%5D.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/01 Boogie Oogie Oogie [Radio Version].wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F02%20You're%20In%20Good%20Hands.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/02 You're In Good Hands.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F03%20Disco%20Dancin'.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/03 Disco Dancin'.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F04%20Do%20It%20Good.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/04 Do It Good.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F05%20Dance.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/05 Dance.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F06%20Rescue%20Me.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/06 Rescue Me.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F07%20I'm%20Talkin'%20'bout%20You.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/07 I'm Talkin' 'bout You.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F08%20Sukiyaki.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/08 Sukiyaki.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F09%20I'll%20Try%20Something%20New.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/09 I'll Try Something New.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F10%20We've%20Got%20The%20Groove.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/10 We've Got The Groove.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F11%20This%20Love%20Of%20Ours.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/11 This Love Of Ours.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=A%20Taste%20Of%20Honey/Classic%20Masters%20(Remastered)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FA%20Taste%20of%20Honey%20-%20A%20Taste%20Of%20Honey%20(Remastered)%20%5B2002%5D%2F12%20I%20Want%20To%20Be%20Your%20Girl.wav&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/A Taste of Honey - A Taste Of Honey (Remastered) [2002]/12 I Want To Be Your Girl.wav Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=ADVANCE//large&path=%2Fmnt%2FUSB%2FMUSICS%2FAdvance%20-%20Take%20Me%20To%20The%20Top%2FADVANCE%20-%20Take%20Me%20To%20The%20Top%20(New%20Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Advance - Take Me To The Top/ADVANCE - Take Me To The Top (New Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=ADVANCE//large&path=%2Fmnt%2FUSB%2FMUSICS%2FAdvance%20-%20Take%20Me%20To%20The%20Top%2FADVANCE%20-%20Take%20Me%20To%20The%20Top%20(Original%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Advance - Take Me To The Top/ADVANCE - Take Me To The Top (Original Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alisha/Baby%20Talk%20(US%2012%22)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlisha%20-%20Baby%20Talk%20(US%2012'')%20(1985)%20%5BDjPaulT%20FLAC%5D%20%7B24bit-96kHz%7D%2F01%20-%20Baby%20Talk%20(Special%20Remix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alisha - Baby Talk (US 12'') (1985) [DjPaulT FLAC] {24bit-96kHz}/01 - Baby Talk (Special Remix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alisha/Baby%20Talk%20(US%2012%22)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlisha%20-%20Baby%20Talk%20(US%2012'')%20(1985)%20%5BDjPaulT%20FLAC%5D%20%7B24bit-96kHz%7D%2F02%20-%20Baby%20Talk%20(Instrumental%20Dub-Vocal%20Dub).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alisha - Baby Talk (US 12'') (1985) [DjPaulT FLAC] {24bit-96kHz}/02 - Baby Talk (Instrumental Dub-Vocal Dub).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alisha/Stargazing%20(US%2012%22%20Promo)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlisha%20-%20Stargazing%20(US%2012''%20Promo)%20(1986)%20%5BDjPaulT%20FLAC%5D%20%7B24bit-96kHz%7D%2F01%20-%20Stargazing.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alisha - Stargazing (US 12'' Promo) (1986) [DjPaulT FLAC] {24bit-96kHz}/01 - Stargazing.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alisha/Stargazing%20(US%2012%22%20Promo)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlisha%20-%20Stargazing%20(US%2012''%20Promo)%20(1986)%20%5BDjPaulT%20FLAC%5D%20%7B24bit-96kHz%7D%2F02%20-%20Stargazing%20(Radio%20Edit).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alisha - Stargazing (US 12'' Promo) (1986) [DjPaulT FLAC] {24bit-96kHz}/02 - Stargazing (Radio Edit).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alisha/Stargazing%20(US%2012%22%20Promo)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlisha%20-%20Stargazing%20(US%2012''%20Promo)%20(1986)%20%5BDjPaulT%20FLAC%5D%20%7B24bit-96kHz%7D%2F03%20-%20Stargazing%20(Dub).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alisha - Stargazing (US 12'' Promo) (1986) [DjPaulT FLAC] {24bit-96kHz}/03 - Stargazing (Dub).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alison%20Moyet/Alf%20(Deluxe%20Edition)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlison%20Moyet%20-%20Invisible%20(The%20Transparent%20Mix)%20(US%2012'')%20(1985)%2F01%20-%20Invisible%20(Extended%20Version).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alison Moyet - Invisible (The Transparent Mix) (US 12'') (1985)/01 - Invisible (Extended Version).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alison%20Moyet/Alf%20(Deluxe%20Edition)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlison%20Moyet%20-%20Invisible%20(The%20Transparent%20Mix)%20(US%2012'')%20(1985)%2F02%20-%20Invisible.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alison Moyet - Invisible (The Transparent Mix) (US 12'') (1985)/02 - Invisible.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alison%20Moyet/Alf%20(Deluxe%20Edition)/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlison%20Moyet%20-%20Invisible%20(The%20Transparent%20Mix)%20(US%2012'')%20(1985)%2F03%20-%20Invisible%20(Transparent%20Mix).flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alison Moyet - Invisible (The Transparent Mix) (US 12'') (1985)/03 - Invisible (Transparent Mix).flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alton%20McClain/Luxury%20Soul%202017/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlton%20McClain%20-%20Your%20Love%20Is%20All%20I%20Need%2FAlton%20McClain%20-%20Your%20Love%20Is%20All%20I%20Need.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alton McClain - Your Love Is All I Need/Alton McClain - Your Love Is All I Need.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Alton%20Mcclain%20%26%20Destiny/Maxi%20Club%20Disco%20Funk%2C%20Vol.%204/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAlton%20McClain%20-%20Your%20Love%20Is%20All%20I%20Need%2FAlton%20Mcclain%20%26%20Destiny%20-%20Crazy%20Love.flac&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Alton McClain - Your Love Is All I Need/Alton Mcclain & Destiny - Crazy Love.flac Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Andy%20Gibb/After%20Dark/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAndy%20Gibb%20-%20After%20Dark%20-%201980%20(CD%20Japan%202013)%2FAndy%20Gibb%20-%20After%20Dark%201980.cue&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Andy Gibb - After Dark - 1980 (CD Japan 2013)/Andy Gibb - After Dark 1980.cue Apr 20 09:49:55 volumio volumio[7305]: info: ALBUMART /albumart?cacheid=247&web=Andy%20Gibb/After%20Dark/large&path=%2Fmnt%2FUSB%2FMUSICS%2FAndy%20Gibb%20-%20After%20Dark%20-%201980%20(CD%20Japan%202013)%2FAndy%20Gibb%20-%20After%20Dark%201980.cue&metadata=false Apr 20 09:49:55 volumio volumio[7305]: info: URI /mnt/USB/MUSICS/Andy Gibb - After Dark - 1980 (CD Japan 2013)/Andy Gibb - After Dark 1980.cue Apr 20 09:49:55 volumio volumio[7305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:49:55 volumio volumio[7305]: Error: Unable to resolve or reject the same promise twice Apr 20 09:49:55 volumio volumio[7305]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 20 09:49:55 volumio volumio[7305]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 20 09:49:55 volumio volumio[7305]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 20 09:49:55 volumio volumio[7305]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 20 09:49:55 volumio volumio[7305]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 20 09:49:55 volumio volumio[7305]: at Socket.emit (events.js:315:20) Apr 20 09:49:55 volumio volumio[7305]: at addChunk (internal/streams/readable.js:309:12) Apr 20 09:49:55 volumio volumio[7305]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 20 09:49:55 volumio volumio[7305]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 20 09:49:55 volumio volumio[7305]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 20 09:49:55 volumio volumio[7305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:49:55 volumio go-librespot[17714]: time="2024-04-20T09:49:55Z" level=debug msg="completed challenge" Apr 20 09:49:55 volumio go-librespot[17714]: time="2024-04-20T09:49:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:49:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:49:56 volumio sudo[17731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 09:48 Apr 20 09:49:56 volumio sudo[17731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:49:56 volumio sudo[17731]: pam_unix(sudo:session): session closed for user root Apr 20 09:49:56 volumio volumio-remote-updater[520]: [2024-04-20 09:49:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 20 09:49:56 volumio volumio-remote-updater[520]: [2024-04-20 09:49:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 20 09:49:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:49:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 09:49:56 volumio systemd[1]: Started dynamicswap service. Apr 20 09:49:56 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 20 09:49:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 09:49:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 20 09:49:56 volumio systemd[1]: Started dynamicswap service. Apr 20 09:49:56 volumio systemd[1]: Stopped Volumio Backend Module. Apr 20 09:49:56 volumio systemd[1]: Started Volumio Backend Module. Apr 20 09:49:56 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 20 09:49:58 volumio volumio[17745]: info: ------------------------------------------- Apr 20 09:49:58 volumio volumio[17745]: info: ----- Volumio3 ---- Apr 20 09:49:58 volumio volumio[17745]: info: ------------------------------------------- Apr 20 09:49:58 volumio volumio[17745]: info: ----- System startup ---- Apr 20 09:49:58 volumio volumio[17745]: info: ------------------------------------------- Apr 20 09:49:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:49:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 811. Apr 20 09:49:58 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:49:59 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:49:59 volumio go-librespot[17767]: Librespot-go daemon starting... Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=info msg="generated new device id: 22840543c806f35d94f83dd57c07aca45f68be01" Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=debug msg="obtained new client token: AAA/pY2aNnsprrAugvaGMLezlxN61gYce1GoTgdhBDR2qJPorprMosQdq/pWF6LwbmM5AL9m7SUSWdyQVlLgrwavbJCUxBYF8B4VmkVQ7s+cMkIo8bWY8YidYnlUhWMNWUUGI5ydUr9aqTa6vMDHiLuey7DOFH+wTykTmgTASuWicsqBq4muFL5VERM9Vpk9h65cZf5+qaJsuhkjrVpdm/NByvD+5eTfFTh8GbZDPPScKlqD8XK9aBQrLyo8+6k=" Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" 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]" Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=debug msg="completed keyexchange" Apr 20 09:49:59 volumio volumio[17745]: info: MYVOLUMIO Environment detected Apr 20 09:49:59 volumio volumio[17745]: info: Plugin folders cleanup Apr 20 09:49:59 volumio volumio[17745]: info: Scanning into folder /volumio/app/plugins/ Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category audio_interface Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category miscellanea Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category music_service Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category plugins.json Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category system_controller Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category user_interface Apr 20 09:49:59 volumio volumio[17745]: info: Scanning into folder /data/plugins/ Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category audio_interface Apr 20 09:49:59 volumio volumio[17745]: info: Scanning category music_service Apr 20 09:49:59 volumio volumio[17745]: info: Plugin folders cleanup completed Apr 20 09:49:59 volumio volumio[17745]: info: ------------------------------------------- Apr 20 09:49:59 volumio volumio[17745]: info: ----- Core plugins startup ---- Apr 20 09:49:59 volumio volumio[17745]: info: ------------------------------------------- Apr 20 09:49:59 volumio volumio[17745]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 09:49:59 volumio volumio[17745]: info: Adding plugin upnp to MyMusic Plugins Apr 20 09:49:59 volumio volumio[17745]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 09:49:59 volumio volumio[17745]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 09:49:59 volumio volumio[17745]: info: Loading plugins from folder /data/plugins/ Apr 20 09:49:59 volumio volumio[17745]: info: Loading plugin "system"... Apr 20 09:49:59 volumio volumio[17745]: info: Loading plugin "appearance"... Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=debug msg="completed challenge" Apr 20 09:49:59 volumio go-librespot[17767]: time="2024-04-20T09:49:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "network"... Apr 20 09:50:01 volumio volumio[17745]: info: Refreshing Cached IP Addresses Apr 20 09:50:01 volumio sudo[17782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 09:50:01 volumio sudo[17782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio sudo[17782]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:01 volumio sudo[17784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "services"... Apr 20 09:50:01 volumio sudo[17784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "alsa_controller"... Apr 20 09:50:01 volumio sudo[17784]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:01 volumio sudo[17787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 09:50:01 volumio sudo[17787]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio volumio-remote-updater[520]: [2024-04-20 09:50:01] [connect] Successful connection Apr 20 09:50:01 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "wizard"... Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "networkfs"... Apr 20 09:50:01 volumio volumio[17745]: info: Starting Udev Watcher for removable devices Apr 20 09:50:01 volumio sudo[17796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Victor@1968,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.88/Volume_1 /mnt/NAS/Volume_1 Apr 20 09:50:01 volumio sudo[17796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio volumio[17745]: info: Ignoring mount for partition: boot Apr 20 09:50:01 volumio volumio[17745]: info: Ignoring mount for partition: volumio Apr 20 09:50:01 volumio volumio[17745]: info: Ignoring mount for partition: volumio_data Apr 20 09:50:01 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:01 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:01 volumio kernel: CIFS: Attempting to mount \\192.168.1.88\Volume_1 Apr 20 09:50:01 volumio sudo[17796]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:01 volumio kernel: CIFS: Status code returned 0xc000006d NT_STATUS_LOGON_FAILURE Apr 20 09:50:01 volumio kernel: CIFS: VFS: \\192.168.1.88 Send error in SessSetup = -13 Apr 20 09:50:01 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 09:50:01 volumio volumio[17745]: info: Mounting Device MUSICS Apr 20 09:50:01 volumio sudo[17805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/MUSICS -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 20 09:50:01 volumio sudo[17805]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio sudo[17805]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:01 volumio volumio[17745]: mount: /media/MUSICS: /dev/sdb1 already mounted on /media/MUSICS. Apr 20 09:50:01 volumio volumio[17745]: error: Failed to mount MUSICS: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/MUSICS" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 20 09:50:01 volumio volumio[17745]: mount: /media/MUSICS: /dev/sdb1 already mounted on /media/MUSICS. Apr 20 09:50:01 volumio volumio[17745]: info: Mounting Device MUSICS2 Apr 20 09:50:01 volumio sudo[17811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/MUSICS2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 20 09:50:01 volumio sudo[17811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:01 volumio sudo[17811]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:01 volumio volumio[17745]: mount: /media/MUSICS2: /dev/sda1 already mounted on /media/MUSICS2. Apr 20 09:50:01 volumio volumio[17745]: error: Failed to mount MUSICS2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/MUSICS2" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 20 09:50:01 volumio volumio[17745]: mount: /media/MUSICS2: /dev/sda1 already mounted on /media/MUSICS2. Apr 20 09:50:01 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "volumio_command_line_client"... Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "upnp"... Apr 20 09:50:01 volumio volumio[17745]: info: [1713606601922] Starting Upmpd Daemon Apr 20 09:50:01 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "my_music"... Apr 20 09:50:01 volumio volumio[17745]: info: Loading plugin "mpd"... Apr 20 09:50:02 volumio volumio[17745]: info: Loading plugin "upnp_browser"... Apr 20 09:50:02 volumio volumio[17745]: info: Loading plugin "alarm-clock"... Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "airplay_emulation"... Apr 20 09:50:03 volumio volumio[17745]: info: Starting Shairport Sync Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "last_100"... Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "webradio"... Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "i2s_dacs"... Apr 20 09:50:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 812. Apr 20 09:50:03 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "volumiodiscovery"... Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** For more information see Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 09:50:03 volumio node[17745]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:50:03 volumio volumio[17745]: *** WARNING *** For more information see Apr 20 09:50:03 volumio node[17745]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:50:03 volumio node[17745]: *** WARNING *** For more information see Apr 20 09:50:03 volumio node[17745]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 09:50:03 volumio node[17745]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:50:03 volumio node[17745]: *** WARNING *** For more information see Apr 20 09:50:03 volumio volumio[17745]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 09:50:03 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:03 volumio volumio[17745]: info: Discovery: Started advertising with name: Volumio Apr 20 09:50:03 volumio go-librespot[17813]: Librespot-go daemon starting... Apr 20 09:50:03 volumio go-librespot[17813]: time="2024-04-20T09:50:03Z" level=info msg="generated new device id: cc90c66dd5cf793ccc36be41e4e33fd758746f08" Apr 20 09:50:03 volumio go-librespot[17813]: time="2024-04-20T09:50:03Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:03 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:50:03 volumio volumio[17745]: info: Loading plugin "spop"... Apr 20 09:50:03 volumio go-librespot[17813]: time="2024-04-20T09:50:03Z" level=debug msg="obtained new client token: AAAc0EhH+YmMuqW9cZQ7nB70HKJaLUbNDVnqbIlTc8CnuTiGnWscwt7DHtOE/I2Af1iF4iAWCBzHezdZojE1xIdjJxs+VA7aUa/isgimVPQ/rGg5roidWXm3pX7TautZrraT+hXTl1clf7GjvhQLNATG+47OjAU/dUbQGvOkPr2tpsjZ0vHTDRmtH22EznIF/mMNsGzvcO7EK7VebLgVC73m2IHyqj5B6BKKHr9opdDx49Ky/WsuFozcakw7OEQ=" Apr 20 09:50:03 volumio go-librespot[17813]: time="2024-04-20T09:50:03Z" 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]" Apr 20 09:50:03 volumio go-librespot[17813]: time="2024-04-20T09:50:03Z" level=debug msg="completed keyexchange" Apr 20 09:50:04 volumio go-librespot[17813]: time="2024-04-20T09:50:04Z" level=debug msg="completed challenge" Apr 20 09:50:04 volumio go-librespot[17813]: time="2024-04-20T09:50:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:04 volumio sudo[17787]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "outputs"... Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "albumart"... Apr 20 09:50:05 volumio volumio[17745]: info: Plugin example_plugin is not enabled Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "inputs"... Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "updater_comm"... Apr 20 09:50:05 volumio volumio[17745]: info: Plugin mpdemulation is not enabled Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "rest_api"... Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "websocket"... Apr 20 09:50:05 volumio volumio[17745]: info: Plugin fusiondsp is not enabled Apr 20 09:50:05 volumio volumio[17745]: info: Loading plugin "RoonBridge"... Apr 20 09:50:05 volumio volumio[17745]: Forking 3 albumart workers Apr 20 09:50:05 volumio volumio[17745]: info: Applying required configuration parameters for plugin RoonBridge Apr 20 09:50:05 volumio volumio[17745]: info: Loading i18n strings for locale fr Apr 20 09:50:05 volumio volumio[17745]: Updating browse sources language Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::initPlayerControls Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:05 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:50:06 volumio volumio[17745]: Express server listening on port 3000 Apr 20 09:50:06 volumio volumio[17745]: [Metrics] WebUI: 8s 59.40ms Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::resetVolumioState Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::getcurrentVolume Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 09:50:06 volumio volumio[17745]: info: Cannot mount NAS Volume_1 at system boot, trial number 1 ,retrying in 5 seconds Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 1 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 2 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 4 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Apr 20 09:50:06 volumio volumio[17745]: Starting albumart workers Apr 20 09:50:06 volumio volumio[17745]: Starting albumart workers Apr 20 09:50:06 volumio volumio[17745]: Starting albumart workers Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 10 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Apr 20 09:50:06 volumio volumio[17745]: info: Reloading queue from file Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:50:06 volumio volumio[17745]: info: VolumeController:: Volume=37 Mute =false Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::pushState Apr 20 09:50:06 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::volumioPushState Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::updateTrackBlock Apr 20 09:50:06 volumio volumio[17745]: info: CorePlayQueue::getTrackBlock Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 09:50:06 volumio volumio[17745]: info: Setting Device type: Raspberry PI Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::setRepeat null single undefined Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::pushState Apr 20 09:50:06 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::volumioPushState Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::setRandom false Apr 20 09:50:06 volumio volumio[17745]: info: CoreStateMachine::pushState Apr 20 09:50:06 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:50:06 volumio volumio[17745]: info: CoreCommandRouter::volumioPushState Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 12 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 13 Apr 20 09:50:06 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 14 Apr 20 09:50:07 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 15 Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: adding a78b07c0-2273-4cab-a88c-22fb9529ec8a Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: Found device Volumio Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: Received Get System Info Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: Getting this device information Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 09:50:07 volumio volumio[17745]: info: Received Get System Info Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: Getting this device information Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: Listing playlists Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetQueue Apr 20 09:50:07 volumio volumio[17745]: info: CoreStateMachine::getQueue Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getQueue Apr 20 09:50:07 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 16 Apr 20 09:50:07 volumio volumio[17745]: info: VolumeController:: Volume=37 Mute =false Apr 20 09:50:07 volumio volumio[17745]: info: CoreStateMachine::pushState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioPushState Apr 20 09:50:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 813. Apr 20 09:50:07 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:07 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 17 Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: this is already registered, a78b07c0-2273-4cab-a88c-22fb9529ec8a Apr 20 09:50:07 volumio volumio[17745]: info: Discovery: Found device Volumio Apr 20 09:50:07 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:07 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:07 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:07 volumio go-librespot[17889]: Librespot-go daemon starting... Apr 20 09:50:07 volumio go-librespot[17889]: time="2024-04-20T09:50:07Z" level=info msg="generated new device id: 4c87aab4485243288b224740d3318f6d8b713441" Apr 20 09:50:07 volumio go-librespot[17889]: time="2024-04-20T09:50:07Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:07 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 18 Apr 20 09:50:07 volumio go-librespot[17889]: time="2024-04-20T09:50:07Z" level=debug msg="obtained new client token: AACIewLnqkYyaONNer4ffWTpazahN+TnDVZiUwwEhDlrfpxpgzTvPNtTK/iXcXNbthBkFILce54TVRaVH3scMovG0bIBtfdpe/+6AhbKN0tjwZL0oK+JAIdthMjxO90hJBqLRH39Rd48chyUMr4yCmUt7LLTLK40/XtIEtogKN8/iRhhWgAgNvUFZalFz9vT2+j7yvHCxB8azz+M0Omm04ugwXfLetkppUqfjTQjUtvnL526eEYCbQW7qBmNhvY=" Apr 20 09:50:07 volumio go-librespot[17889]: time="2024-04-20T09:50:07Z" 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]" Apr 20 09:50:07 volumio go-librespot[17889]: time="2024-04-20T09:50:07Z" level=debug msg="completed keyexchange" Apr 20 09:50:08 volumio go-librespot[17889]: time="2024-04-20T09:50:08Z" level=debug msg="completed challenge" Apr 20 09:50:08 volumio go-librespot[17889]: time="2024-04-20T09:50:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:09 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:09 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:09 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:10 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:10 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:10 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:11 volumio sudo[17899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Victor@1968,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.88/Volume_1 /mnt/NAS/Volume_1 Apr 20 09:50:11 volumio sudo[17899]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:11 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:11 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:11 volumio kernel: CIFS: Attempting to mount \\192.168.1.88\Volume_1 Apr 20 09:50:11 volumio sudo[17899]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:11 volumio volumio[17745]: info: Cannot mount NAS Volume_1 at system boot, trial number 2 ,retrying in 5 seconds Apr 20 09:50:11 volumio kernel: CIFS: Status code returned 0xc000006d NT_STATUS_LOGON_FAILURE Apr 20 09:50:11 volumio kernel: CIFS: VFS: \\192.168.1.88 Send error in SessSetup = -13 Apr 20 09:50:11 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 09:50:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 814. Apr 20 09:50:11 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:11 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:11 volumio go-librespot[17904]: Librespot-go daemon starting... Apr 20 09:50:11 volumio go-librespot[17904]: time="2024-04-20T09:50:11Z" level=info msg="generated new device id: 51b9e2289b29137b0898e5119a90d91b3aae51db" Apr 20 09:50:11 volumio go-librespot[17904]: time="2024-04-20T09:50:11Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:11 volumio sudo[17913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 09:50:11 volumio sudo[17913]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:11 volumio sudo[17913]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:11 volumio sudo[17915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:50:11 volumio sudo[17915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:11 volumio sudo[17915]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:12 volumio sudo[17919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 09:50:12 volumio sudo[17919]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:12 volumio go-librespot[17904]: time="2024-04-20T09:50:12Z" level=debug msg="obtained new client token: AABTwgkwAjjEhvCQwK1Q6Ze+KZumTIcMXKLkixMXOtfY0ya9Dm1Gl8MBVfzMBGCNuuXO5ngh6AhXpDrgJsSTOIgkDEv4d05lC9XHI0gohnIwDJuSVYadjMOlw7Ox45qz/V51VRLPGK0p+mmgT4OfkTTNRLB0rsVD705WHsqKiLwN5BqhrRMoY2964GCbQVSAOKhJmxpuxvJslYRfwOTBd37V3WXgUchC9hxY6+3uL47rdxsssg5fpNS+lt7j" Apr 20 09:50:12 volumio sudo[17919]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:12 volumio volumio[17745]: info: Upmpdcli Daemon Started Apr 20 09:50:12 volumio go-librespot[17904]: time="2024-04-20T09:50:12Z" 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]" Apr 20 09:50:12 volumio go-librespot[17904]: time="2024-04-20T09:50:12Z" level=debug msg="completed keyexchange" Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:12 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:12 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:12 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.24 UA: unknown Total Clients: 19 Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:12 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 09:50:12 volumio volumio[17745]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.24 UA: unknown Total Clients: 20 Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::volumioGetState Apr 20 09:50:12 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 09:50:12 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 09:50:12 volumio go-librespot[17904]: time="2024-04-20T09:50:12Z" level=debug msg="completed challenge" Apr 20 09:50:12 volumio go-librespot[17904]: time="2024-04-20T09:50:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:13 volumio volumio-remote-updater[520]: [2024-04-20 09:50:13] [connect] Successful connection Apr 20 09:50:13 volumio volumio-remote-updater[520]: [2024-04-20 09:50:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713606613 101 Apr 20 09:50:13 volumio volumio[17745]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 21 Apr 20 09:50:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 815. Apr 20 09:50:15 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:15 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:15 volumio go-librespot[17921]: Librespot-go daemon starting... Apr 20 09:50:15 volumio go-librespot[17921]: time="2024-04-20T09:50:15Z" level=info msg="generated new device id: bebb65a23c94fb444894a9fdc1fdd0dbbbbc0903" Apr 20 09:50:15 volumio go-librespot[17921]: time="2024-04-20T09:50:15Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:16 volumio go-librespot[17921]: time="2024-04-20T09:50:16Z" level=debug msg="obtained new client token: AACOB+xnZYJnigyNBzWggSwNpZanRMmkVTR19IARb/ZxPkpCVzz25wm9w10cosS6qxuwUiB8mqUMIYSj73oY1fizSqOMehorCyn9Io5Ygh5HllF2s0ngJgXhpdtA54O6pGcIlDXHuwmk7vtNtqPk9PGDP92bX4zxZ/lnh6oVCDdUSFA7Vc13j6eCkRxxfOOg6XgWMeT2Hbm/+UL/blU/PzydSIRXhSfkhTvzgvNlCRP9/EkUxj8EuuRTzoArR6w=" Apr 20 09:50:16 volumio go-librespot[17921]: time="2024-04-20T09:50:16Z" 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]" Apr 20 09:50:16 volumio go-librespot[17921]: time="2024-04-20T09:50:16Z" level=debug msg="completed keyexchange" Apr 20 09:50:16 volumio sudo[17931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Victor@1968,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.88/Volume_1 /mnt/NAS/Volume_1 Apr 20 09:50:16 volumio sudo[17931]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:16 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:16 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:16 volumio kernel: CIFS: Attempting to mount \\192.168.1.88\Volume_1 Apr 20 09:50:16 volumio sudo[17931]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:16 volumio volumio[17745]: info: Cannot mount NAS Volume_1 at system boot, trial number 3 ,retrying in 5 seconds Apr 20 09:50:16 volumio kernel: CIFS: Status code returned 0xc000006d NT_STATUS_LOGON_FAILURE Apr 20 09:50:16 volumio kernel: CIFS: VFS: \\192.168.1.88 Send error in SessSetup = -13 Apr 20 09:50:16 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 09:50:16 volumio go-librespot[17921]: time="2024-04-20T09:50:16Z" level=debug msg="completed challenge" Apr 20 09:50:16 volumio go-librespot[17921]: time="2024-04-20T09:50:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:17 volumio volumio[17745]: info: Preload queue cleared Apr 20 09:50:17 volumio volumio[17745]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 20 09:50:17 volumio volumio[17745]: info: CoreStateMachine::ClearQueue Apr 20 09:50:17 volumio volumio[17745]: info: CoreStateMachine::stop Apr 20 09:50:17 volumio volumio[17745]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:50:17 volumio volumio[17745]: info: CorePlayQueue::clearPlayQueue Apr 20 09:50:17 volumio volumio[17745]: info: CorePlayQueue::saveQueue Apr 20 09:50:17 volumio volumio[17745]: info: CoreCommandRouter::volumioPushQueue Apr 20 09:50:17 volumio volumio[17745]: info: CoreStateMachine::addQueueItems Apr 20 09:50:17 volumio volumio[17745]: info: CorePlayQueue::addQueueItems Apr 20 09:50:17 volumio volumio[17745]: info: Preload queue cleared Apr 20 09:50:17 volumio volumio[17745]: info: Adding Item to queue: music-library/USB/MUSICS2 Apr 20 09:50:17 volumio volumio[17745]: info: Exploding uri music-library/USB/MUSICS2 in service mpd Apr 20 09:50:17 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)/CD1' Apr 20 09:50:17 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)/CD2' Apr 20 09:50:17 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)/CD3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)/CD4' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)/CD5' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/100 Essential Soul & Funk Classics (2010)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Akira Inoue, Masataka Matsutoya, Hiroshi Sato - Seaside Lovers - Memories In Beach House (2013) [16-44]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Alisha - Alisha Expanded 1985/Disc 1 - Alisha' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Alisha - Alisha Expanded 1985/Disc 2 - More Alternate Mixes & Dubs' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Alisha - Alisha Expanded 1985' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Amy Winehouse - At The BBC/CD1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Amy Winehouse - At The BBC/CD2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Amy Winehouse - At The BBC/CD3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Amy Winehouse - At The BBC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Armenta - I Wanna Be with You (CD, Maxi SingleUS)(2012)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes [Disc 1]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes [Disc 2]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes [Disc 3]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes [Disc 4]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Arthur Baker Presents Dance Masters- John Luongo The Classic Master-Mixes' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Audiophile HQ HiFi Demo Room Vol 01 [FLAC]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Barry White & Lou Rawls - Back To Back (The Best Of)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Barry White - Greatest Hits [Mercury - 16479-1_2] [2xCD, Comp, REI, Unofficial Release] [Russia, 2008] [FLAC]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Bear McCreary - Foundation Season 1 (Apple TV Original Series Soundtrack) (2021) FLAC 24bit' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Big Fun Gary & Barnacle & Sonia - You've Got A Friend [DE] [Maxi]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Bisquit - Roller Boogie 1981' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Blue Feather - Let's Funk Tonight 1981' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Boney M. - The Maxi-Single Collection/Boney M. - The Maxi-Single Collection Volume 1 (WAV)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Boney M. - The Maxi-Single Collection/Boney M. - The Maxi-Single Collection Volume 2 (WAV)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Boney M. - The Maxi-Single Collection/Boney M. - The Maxi-Single Collection Volume 3 (WAV)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Boney M. - The Maxi-Single Collection/Boney M. - The Maxi-Single Collection Volume 4 (WAV)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Boney M. - The Maxi-Single Collection' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/CALADAN Sound Clips 24-192' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Cerrone - Cerrone by Cerrone (16 x File, FLAC, Album, Stereo, 24-Bit 44.1 kHz, France 2022)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Chris Stapleton - From A Room - Volume 1 (Mercury, 2017)(HD 24-96)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Dance Classics - The Remixes Vol. 1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Dance Classics - The Remixes Vol. 2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Dance Classics - The Remixes Vol. 3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Dance Classics - The Remixes Vol. 4' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/David Gilmour - Live At Pompeii (2017) [FLAC]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Divers 70s' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Earth, Wnd & Fire - Elements of Love - The Ballads 1996' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Elisa Fiorillo - How Can I Forget You' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Firefly - 12 Inch Classics On CD (CDM-RI) [1981^1993]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/HYBS - Coldcuts' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/HYBS - Genre Less' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/HYBS - Making Steak' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/HYBS - NoCap PART2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Hans Zimmer - LIVE 2023 HI-RES' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/High-end Audiophile Test Demo CD2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/High-end Audiophile Test Demo CD3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Imagination Feat. Leee John - 40 Years (EDSL0114) (Boxset 17CD) (2023)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Janet Jackson - Design Of A Decade 1986 - 1996 (1995) (Pop, R_B, Soul) (Flac 16-44.1khz)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Janet Jackson - Rhythm Nation 1814 - 1989 A&M Records' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/John Williams & Steven Spielberg - The Ultimate Collection (2017)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/John Williams-Indiana Jones and the Dial of Destiny FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Jung Kook - Golden' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Karen Carpenter' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Kool & The Gang - Emergency (France 7'') (1985) [DjPaulT FLAC] {24bit-96kHz}' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Leon Haywood - 1983 - I'm Out To Catch (12'' vinyl) (FLAC)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Luther Vandross - 1989 - Never Too Much (Remix 89)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD1. Burning Up' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD10. Love Don_t Live Here Anymore' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD11. Live To Tell' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD12. Papa Don_t Preach' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD13. True Blue' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD14. Open Your Heart' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD15. La Isla Bonita' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD16. Who_s That Girl' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD17. Causing A Commotion' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD18. Spotlight' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD19. The Look Of Love' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD2. Holiday' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD20. Like A Prayer' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD21. Express Yourself' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD22. Cherish' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD23. Oh Father' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD24. Keep It Together' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD25. Vogue' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD26. Hanky Panky' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD27. Justify My Love' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD28. Rescue Me' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD29. This Used To Be My Playground' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD3. Borderline' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD30. Erotica' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD31. Deeper And Deeper' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD32. Bad Girl' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD33. Bye Bye Baby' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD34. I_ll Remember' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD35. Secret' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD36. Take A Bow' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD37. Human Nature' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD38. Crazy For You' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD39. You_ll See' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD4. Lucky Star' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD40. One More Chance' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD5. Like A Virgin' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD6. Material Girl' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD7. Angel' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD8. Into The Groove' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)/CD9. Dress You Up' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - CD Single Collection Box (Japan CD FLAC) (1996)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - Golden Girl Mixes (On-Usound CD)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Madonna - You Can Dance [Single Edits][2022 Remastered][Promo CD]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Mariya Takeuchi - Plastic Love (Japan 12 Maxi Single 1985)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Mariya Takeuchi - Plastic Love Remaster EP 24bit-96kHz' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Maxine Singleton - Don't You Love It' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Melba Moore - You Stepped Into My Life' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Michael Jackson - Thriller [Mobile Fidelity Sound Lab ‎- UDSACD 2251][SACD, Hybrid, Album, Num, REI, REM, SE] [US, 2022] [Hi-Res] [24Bit-48 kHz.]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Narada Michael Walden - Tonight I'm Alright (Maxi Single) 1979' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/PWL Extended (Big Hits And Surprises Vols.1 & 2)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Paul McCartney & Wings - Wings Greatest (1978) (Remastered) (2020) [24-44,1]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Pet Shop Boys - Bilingual' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Pet Shop Boys - Somewhere [Single]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Remixes 5/BodyAlive Remix' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Remixes 5/Hardys Master Mix' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Remixes 5/Joy Remix' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Remixes 5/Maik Schafer Mixe' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Remixes 5/Zwunk Remix' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Rick Astley - Never Gonna Give You Up [24bit FLAC] vinyl' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Ronnie Milsap - Sings His Best Hits For Capitol Records (2020) [Reissue]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Roy Gaines - Superman/Covers' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Roy Gaines - Superman' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/S.O.S. Band - The Official Bootleg Mega-Mix, Just Be Good To Me' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/SAM Records - Extended Play - 12 Inch Disco Classics (2008)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Shiva - Never Gonna Give You Up' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Shiva - What Does It Take' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Silvetti' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Space Cobra - Complete Soundtrack/CD1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Space Cobra - Complete Soundtrack/CD2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Space Cobra - Complete Soundtrack' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Stars On 45 - 40 Years Anthology (Remastered) (2022) [16Bit]/Disc 1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Stars On 45 - 40 Years Anthology (Remastered) (2022) [16Bit]/Disc 2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Stars On 45 - 40 Years Anthology (Remastered) (2022) [16Bit]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/CD1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/CD2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/CD3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/CD4' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/CD5' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)/Cover' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Studio 54 - 5th Edition (The Biggest And Best Disco Collection Ever!) (2006)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Surface - Falling In Love - Stop Holdin' Back' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/TOP GUN MAVERICK (MUSIC FROM THE MOTION PICTURE) [Interscope Records,2022]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Telarc - Erich Kunzel and the Cincinnati Pops Orchestra - Fantastic Journey FLAC/Artwork' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Telarc - Erich Kunzel and the Cincinnati Pops Orchestra - Fantastic Journey FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Telarc - Erich Kunzel and the Cincinnati Pops Orchestra - Star Tracks I FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Telarc - Erich Kunzel and the Cincinnati Pops Orchestra - Star Tracks II FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Telarc - Erich Kunzel and the Cincinnati Pops Orchestra - Time Warp FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Terje Isungset - Winter Songs (2010)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/The Blow Monkeys - Choices The Singles Collection' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/The Danish National Symphony Orchestra, Christian Schumann - Fantasymphony (2020)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/The John Barry Orchestra - Best of Bond ... 50 Years' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/The Sax Pack - Power Of 3 - 2015' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Tina Charles - I Love To Love (Plus)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Tina Charles - I Love To Love- The Best Of Tina Charles [1994]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Tina Charles - I Love to Love The Best Of… (2000)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Usa From Africa - We Are The World 24-96' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Cello Adagios/Cello Adagios [Disc 1]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Cello Adagios/Cello Adagios [Disc 2]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Cello Adagios' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Guitar Adagios/Guitar Adagios [Disc 1]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Guitar Adagios/Guitar Adagios [Disc 2]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Guitar Adagios' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Masters Of The Guitar' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Relaxing Adagios/Relaxing Adagios [Disc 1]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Relaxing Adagios/Relaxing Adagios [Disc 2]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Relaxing Adagios' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Saturday Night Fever OST- 40th anniversary super deluxe edition box set Vinyl RIP' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Serve Chilled 1 FLAC' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Studio 54 [1998] OST 2CD/Disc 1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Studio 54 [1998] OST 2CD/Disc 2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - Studio 54 [1998] OST 2CD' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Best of Bond... James Bond (Vinyl Rip) - 2021 - Album - FLAC (24Bits) HI-RES' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Supreme Record Company (2020) [FLAC]/CD1' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Supreme Record Company (2020) [FLAC]/CD2' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Supreme Record Company (2020) [FLAC]/CD3' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Supreme Record Company (2020) [FLAC]/CD4' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/VA - The Supreme Record Company (2020) [FLAC]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Van McCoy - FLAC - 12 Inch Classics On Cd -' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Vangelis - Blade Runner Trilogy' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Various - Grease (The Original Soundtrack From The Motion Picture)[2xCD, Deluxe Edition, Remastered, 25th Anniversary][US, 2003][FLAC]/ArtWork' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Various - Grease (The Original Soundtrack From The Motion Picture)[2xCD, Deluxe Edition, Remastered, 25th Anniversary][US, 2003][FLAC]/Disc 1 - Grease Remastered' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Various - Grease (The Original Soundtrack From The Motion Picture)[2xCD, Deluxe Edition, Remastered, 25th Anniversary][US, 2003][FLAC]/Disc 2 - Grease Bonus Disc' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Various - Grease (The Original Soundtrack From The Motion Picture)[2xCD, Deluxe Edition, Remastered, 25th Anniversary][US, 2003][FLAC]' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Voggue - Dancin' The Night Away (Best Of)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Voggue - I Love To Dance 1983' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Wham - Last Christmas' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Wham! - Careless whisper (1984)' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/Whitney Houston - The Unreleased Mixes' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/绝对发烧16' Apr 20 09:50:18 volumio volumio[17745]: error: Failed to stat '/mnt/USB/MUSICS2/绝对发烧19' Apr 20 09:50:18 volumio volumio[17745]: info: CoreCommandRouter::volumioPushQueue Apr 20 09:50:18 volumio volumio[17745]: info: CorePlayQueue::saveQueue Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::updateTrackBlock Apr 20 09:50:18 volumio volumio[17745]: info: CorePlayQueue::getTrackBlock Apr 20 09:50:18 volumio volumio[17745]: info: CoreCommandRouter::volumioPlay Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::play index 0 Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::stop Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::play index undefined Apr 20 09:50:18 volumio volumio[17745]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:50:18 volumio volumio[17745]: info: CorePlayQueue::getTrack 0 Apr 20 09:50:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 816. Apr 20 09:50:19 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:19 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:19 volumio go-librespot[17936]: Librespot-go daemon starting... Apr 20 09:50:19 volumio go-librespot[17936]: time="2024-04-20T09:50:19Z" level=info msg="generated new device id: 590611b79936d2743a241272a328166644584d43" Apr 20 09:50:19 volumio go-librespot[17936]: time="2024-04-20T09:50:19Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:19 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:19 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:19 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:20 volumio go-librespot[17936]: time="2024-04-20T09:50:20Z" level=debug msg="obtained new client token: AABTeLYsU6cQbG0k/WLAzlv5d6H2Nf8mGJ+zkqXxJsFvTiq+yu7yNv3X+R9bQR4Icc6ODd9gCZiJhJHczIN7YkCHKFL+7oT9gmR9tr/ojBjv85NNVeG/3ZnbNrvblGYpBro2ioq0YhjCb7JG7kXPLOoQZefN5tMLLXlFrXTlr54S2o16/lKFK5GCL+tyTR6Spbetzahd/FRmtjrRpiTl1NiB0AEsaWXaNlCqwxRZGSDbmlY9GW6OByQEsoX+NPo=" Apr 20 09:50:20 volumio go-librespot[17936]: time="2024-04-20T09:50:20Z" 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]" Apr 20 09:50:20 volumio go-librespot[17936]: time="2024-04-20T09:50:20Z" level=debug msg="completed keyexchange" Apr 20 09:50:20 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:20 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:20 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:20 volumio go-librespot[17936]: time="2024-04-20T09:50:20Z" level=debug msg="completed challenge" Apr 20 09:50:20 volumio go-librespot[17936]: time="2024-04-20T09:50:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:20 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:20 volumio volumio[17745]: info: CURURI: music-library/USB/MUSICS2 Apr 20 09:50:20 volumio volumio[17745]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 20 09:50:21 volumio sudo[17945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Victor@1968,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.88/Volume_1 /mnt/NAS/Volume_1 Apr 20 09:50:21 volumio sudo[17945]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:21 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:21 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:21 volumio kernel: CIFS: Attempting to mount \\192.168.1.88\Volume_1 Apr 20 09:50:21 volumio kernel: CIFS: Status code returned 0xc000006d NT_STATUS_LOGON_FAILURE Apr 20 09:50:21 volumio kernel: CIFS: VFS: \\192.168.1.88 Send error in SessSetup = -13 Apr 20 09:50:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 09:50:21 volumio sudo[17945]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:21 volumio volumio[17745]: info: Cannot mount NAS Volume_1 at system boot, trial number 4 ,retrying in 5 seconds Apr 20 09:50:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 817. Apr 20 09:50:23 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:23 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:23 volumio go-librespot[17963]: Librespot-go daemon starting... Apr 20 09:50:23 volumio go-librespot[17963]: time="2024-04-20T09:50:23Z" level=info msg="generated new device id: 7866ea11e9e3aa0c4760d16fd0edcfb1a9ab62ea" Apr 20 09:50:23 volumio go-librespot[17963]: time="2024-04-20T09:50:23Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:24 volumio go-librespot[17963]: time="2024-04-20T09:50:24Z" level=debug msg="obtained new client token: AABe0TAHjd84eKV3Krh9ocbkhEtskFFa0GHLNBu7r3+OYRVkmSL5DQHJ7rbcjl3eCZH241qjEjy6rEY2QxvImmoHld8Oow3Zi2JTdvchf8pjBkjrVeBLys4rMY7RwPSdD0w5Mzp8AAlr881/Bm/dNxhPO4vDVgU383pndAUh27igg7NnPZDpXmOr+KPqEBIAotgolwLjRZ2oS0S++epxCU9KQkjlD5SGDnLKcXgu5rSAunZHd9Ap6bFKA1HI" Apr 20 09:50:24 volumio go-librespot[17963]: time="2024-04-20T09:50:24Z" 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]" Apr 20 09:50:24 volumio go-librespot[17963]: time="2024-04-20T09:50:24Z" level=debug msg="completed keyexchange" Apr 20 09:50:24 volumio go-librespot[17963]: time="2024-04-20T09:50:24Z" level=debug msg="completed challenge" Apr 20 09:50:24 volumio go-librespot[17963]: time="2024-04-20T09:50:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:26 volumio sudo[17997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Victor@1968,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.88/Volume_1 /mnt/NAS/Volume_1 Apr 20 09:50:26 volumio sudo[17997]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:26 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:26 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Apr 20 09:50:26 volumio kernel: CIFS: Attempting to mount \\192.168.1.88\Volume_1 Apr 20 09:50:26 volumio kernel: CIFS: Status code returned 0xc000006d NT_STATUS_LOGON_FAILURE Apr 20 09:50:26 volumio kernel: CIFS: VFS: \\192.168.1.88 Send error in SessSetup = -13 Apr 20 09:50:26 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 09:50:26 volumio sudo[17997]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:26 volumio volumio[17745]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 20 09:50:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 818. Apr 20 09:50:27 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:28 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:28 volumio go-librespot[18003]: Librespot-go daemon starting... Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=info msg="generated new device id: ebd773cce663bfa84e7dae42adeb6c5be80874d3" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=debug msg="obtained new client token: AAC8daEnktNCsYai0A8vnh0n0qu1QhSqvxhT2vGvEnEk07grXB166jt/E8eTlrgLPDTu86TSEwiiqo37/dXkd2IyKc4iPOm/KmYmMxGrcibFqFq/1PO+EGehaSJvvzkDNkIo6h1ij5L601fYr4O6x+dphuZlTWUdFiHcKF7U3VsMvdyhgqCw0LGS9JLKZfL6N+boDU0Luy1/Rfq56pMN3RD9h9qbRj5hFiJKeXdhlvvdbsj6IhAzAwRnj1AX" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" 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]" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=debug msg="completed keyexchange" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=debug msg="completed challenge" Apr 20 09:50:28 volumio go-librespot[18003]: time="2024-04-20T09:50:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 819. Apr 20 09:50:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:32 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:32 volumio go-librespot[18011]: Librespot-go daemon starting... Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=info msg="generated new device id: 86e24f75bd304e7d97839b4d5bcb5214e9c24fbb" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=debug msg="obtained new client token: AAB9yl+Yr1+bm7IvQ1BIK9+/GnmNM6vC0BSpnVD9RI6lKx+exYJ1ySrkCHkq9RM94iChDiQVrM/nMJnhaFL85s32y3fgV8BMVGP9AHEO7DNpRb3PXAd97MipD1E9BOVw+6XOKRZuZ9UMdVb+jYDjgjFKgmHl4SJwmWiDULQsVlcZDZSyWXmkCZsiNiPzefhqQoVII54esarUzoV+5PSgCcBLMLERRclQErcWsJ4WySxpezsYL56k4NHwgDbg" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" 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]" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=debug msg="completed keyexchange" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=debug msg="completed challenge" Apr 20 09:50:32 volumio go-librespot[18011]: time="2024-04-20T09:50:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:35 volumio volumio[17745]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 20 09:50:35 volumio volumio[17745]: info: Preparing to generate the ALSA configuration file Apr 20 09:50:35 volumio volumio[17745]: info: Asound.conf file unchanged, so no further update is needed Apr 20 09:50:35 volumio volumio[17745]: info: Output device has changed, restarting MPD Apr 20 09:50:35 volumio sudo[18020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 09:50:35 volumio sudo[18020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:35 volumio volumio[17745]: info: Output device has changed, restarting Shairport Sync Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:50:35 volumio sudo[18020]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:35 volumio sudo[18022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 09:50:35 volumio sudo[18022]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:35 volumio systemd[1]: Stopping Music Player Daemon... Apr 20 09:50:35 volumio volumio[17745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 09:50:35 volumio volumio[17745]: info: ___________ START PLUGINS ___________ Apr 20 09:50:35 volumio volumio[17745]: info: ControllerMpd::onStart: Initializing MPD Apr 20 09:50:35 volumio volumio[17745]: info: Creating MPD Configuration file Apr 20 09:50:35 volumio sudo[18029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 09:50:35 volumio sudo[18029]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:50:35 volumio sudo[18029]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:50:35 volumio volumio[17745]: info: [1713606635880] CoreMusicLibrary::Adding element Serveurs Média Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:50:35 volumio sudo[18032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 09:50:35 volumio sudo[18032]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:50:35 volumio volumio[17745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 09:50:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 820. Apr 20 09:50:35 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:50:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 09:50:36 volumio systemd[1]: Started go-librespot Daemon. Apr 20 09:50:36 volumio go-librespot[18037]: Librespot-go daemon starting... Apr 20 09:50:36 volumio systemd[1]: mpd.service: Succeeded. Apr 20 09:50:36 volumio systemd[1]: Stopped Music Player Daemon. Apr 20 09:50:36 volumio volumio[17745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:50:36 volumio systemd[1]: Starting Music Player Daemon... Apr 20 09:50:36 volumio volumio[17745]: info: [1713606636059] CoreMusicLibrary::Adding element Last_100 Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:50:36 volumio volumio[17745]: info: [1713606636065] CoreMusicLibrary::Adding element Webradio Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:50:36 volumio go-librespot[18037]: time="2024-04-20T09:50:36Z" level=info msg="generated new device id: ccb0c3ea05e64a29ed031e8534ada12eca873464" Apr 20 09:50:36 volumio go-librespot[18037]: time="2024-04-20T09:50:36Z" level=debug msg="stored credentials found for vloo68" Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 09:50:36 volumio volumio[17745]: info: Creating Spotify config file Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:50:36 volumio volumio[17745]: info: Volumio Calling Home Apr 20 09:50:36 volumio sudo[18049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 20 09:50:36 volumio sudo[18049]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:50:36 volumio sudo[18049]: pam_unix(sudo:session): session closed for user root Apr 20 09:50:36 volumio go-librespot[18037]: time="2024-04-20T09:50:36Z" level=debug msg="obtained new client token: AACCc93ZxzS5BUfgISLC3HnsXPS2nIBOUs3yWRlVr/S2zJ/Crg02t/YuxGLl+vUYzb7xFlIh163OumeLDY2Fuws8ycUX0x7zzhymXNQyQTlSFlVH38ORrBr1DjmmUYCG6poCjF0h6s3jWbnbAfFZfZdirpBsi9jiUi7TUa7E4NNB6fo3PTlKmx8/4juRaao8/8GCflX45EVC4oFalVlvvq0HVTE9eoifpzp313f6zaO5xYSoUNWjOt2HkOsQcrc=" Apr 20 09:50:36 volumio volumio[17745]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 09:50:36 volumio volumio[17745]: info: CURURI: albums:// Apr 20 09:50:36 volumio volumio[17745]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:50:36 volumio volumio[17745]: TypeError: Cannot read property 'sendCommand' of undefined Apr 20 09:50:36 volumio volumio[17745]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 Apr 20 09:50:36 volumio volumio[17745]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 20 09:50:36 volumio volumio[17745]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:50:36 volumio go-librespot[18037]: time="2024-04-20T09:50:36Z" 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]" Apr 20 09:50:36 volumio go-librespot[18037]: time="2024-04-20T09:50:36Z" level=debug msg="completed keyexchange" Apr 20 09:50:37 volumio go-librespot[18037]: time="2024-04-20T09:50:37Z" level=debug msg="completed challenge" Apr 20 09:50:37 volumio go-librespot[18037]: time="2024-04-20T09:50:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 09:50:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 09:50:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 09:50:37 volumio sudo[18064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 09:49 Apr 20 09:50:37 volumio sudo[18064]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"