-- Logs begin at Fri 2024-08-23 11:54:01 CEST, end at Fri 2024-08-23 14:21:01 CEST. --
Aug 23 14:20:01 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:01 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94781.
Aug 23 14:20:03 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:03 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:03 volumio go-librespot[24987]: Librespot-go daemon starting...
Aug 23 14:20:03 volumio go-librespot[24987]: time="2024-08-23T14:20:03+02:00" level=info msg="generated new device id: 65da5534234aab7ef67eeb4e867a5a3d6f2ed78f"
Aug 23 14:20:03 volumio go-librespot[24987]: time="2024-08-23T14:20:03+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:03 volumio go-librespot[24987]: time="2024-08-23T14:20:03+02:00" level=debug msg="obtained new client token: AADXJT/EymYxpMGaPVAmx8B2PorBvUFGI/S4sdPmk+lzfHnB5xfWJW8TtfbbDkRQvWzkBPPaZFFA09aN3PfYOCsm5vz8qh1SLWaneHE280DQEi+sYqaaHCzoeWkTCPvNs4VcjpNvTk8getMWU5GjiOc2bhf7Qw7HYjuBqgqWUftHxsR/sR9AyLaCLAHzs0PnIfKKjO1RYAXPYtSVV2xc2KnAVLm1jRNJTeZED3wazi3C9XIF0VSkujmRl+u0ElI="
Aug 23 14:20:03 volumio go-librespot[24987]: time="2024-08-23T14:20:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:03 volumio go-librespot[24987]: time="2024-08-23T14:20:03+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:04 volumio go-librespot[24987]: time="2024-08-23T14:20:04+02:00" level=debug msg="completed challenge"
Aug 23 14:20:04 volumio go-librespot[24987]: time="2024-08-23T14:20:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:04 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:04 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94782.
Aug 23 14:20:07 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:07 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:07 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:07 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:07 volumio go-librespot[24995]: Librespot-go daemon starting...
Aug 23 14:20:07 volumio go-librespot[24995]: time="2024-08-23T14:20:07+02:00" level=info msg="generated new device id: ccf82c834da5bf60b6a7f621137b7f307d09ed0f"
Aug 23 14:20:07 volumio go-librespot[24995]: time="2024-08-23T14:20:07+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:07 volumio go-librespot[24995]: time="2024-08-23T14:20:07+02:00" level=debug msg="obtained new client token: AACF8sXFHsbmzcqndNAvwFwQBQsde6ZytJRH0I/ENqeGQhcYvWKztbEKa6A++7HVGPjhP9JmCOg5KVNNamWZ6XUaqVvbBhJYZqxaxe6I66j257ZC1nDWYhk8O8kLIoOwmejF3g6zwXBuWerHlh6dDwpjYYWuZvMkXxwIQctlR24L+OGO5W7R7e33lS8jdM4r8cLjHOVUeMLt5Uk1Yrc0wLQ41sqEKln0eTZqwlLKMSfmjflYQJr6T3togwYwx1U="
Aug 23 14:20:08 volumio go-librespot[24995]: time="2024-08-23T14:20:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:08 volumio go-librespot[24995]: time="2024-08-23T14:20:08+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:08 volumio go-librespot[24995]: time="2024-08-23T14:20:08+02:00" level=debug msg="completed challenge"
Aug 23 14:20:08 volumio go-librespot[24995]: time="2024-08-23T14:20:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:10 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:10 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94783.
Aug 23 14:20:12 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:12 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:12 volumio go-librespot[25004]: Librespot-go daemon starting...
Aug 23 14:20:12 volumio go-librespot[25004]: time="2024-08-23T14:20:12+02:00" level=info msg="generated new device id: a5299856817689d82089cad77e0e3180bfd8677a"
Aug 23 14:20:12 volumio go-librespot[25004]: time="2024-08-23T14:20:12+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:12 volumio go-librespot[25004]: time="2024-08-23T14:20:12+02:00" level=debug msg="obtained new client token: AAAEWuW0qGcuEnqHdrQ/y3CVxA+FTqeZ0CjPcYqg+pJk6Yp3XBxfmnwpWtnduwBbcBgHQxS6IC6QnvlmWJIWJSp8YRRd++6iHANsrxCfiSCQGAZ8OsuxHjoC+BBzJUkaVErjhFVtPYJD5FfjpnAg1fu7Rs9ZriaIESpFTOg1IOdXQw5WqdRFYcTCwHn5H7qgya5n9Kn+Y83u3zbS4fzHua0zR1yIkLUQEmQiI4fRbYtdKDe8ArJeeFhX+fSpA+I="
Aug 23 14:20:12 volumio go-librespot[25004]: time="2024-08-23T14:20:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:12 volumio go-librespot[25004]: time="2024-08-23T14:20:12+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:13 volumio go-librespot[25004]: time="2024-08-23T14:20:13+02:00" level=debug msg="completed challenge"
Aug 23 14:20:13 volumio go-librespot[25004]: time="2024-08-23T14:20:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:13 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:13 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94784.
Aug 23 14:20:16 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:16 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:16 volumio go-librespot[25013]: Librespot-go daemon starting...
Aug 23 14:20:16 volumio go-librespot[25013]: time="2024-08-23T14:20:16+02:00" level=info msg="generated new device id: caf04ba4b35d4c52c5780405925e3aa474a7c9bb"
Aug 23 14:20:16 volumio go-librespot[25013]: time="2024-08-23T14:20:16+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:16 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:16 volumio volumio[795]: info: Connection to go-librespot Websocket established
Aug 23 14:20:16 volumio go-librespot[25013]: time="2024-08-23T14:20:16+02:00" level=debug msg="new websocket client"
Aug 23 14:20:16 volumio go-librespot[25013]: time="2024-08-23T14:20:16+02:00" level=debug msg="obtained new client token: AACyTJBDwQqE661NiFxTcwhmgRkX6oXNfDbYdWU4/+MFW0ctYIwWGAOoeEaP+HWQgVPdQYZm4/ec3tqy8Mh5us6cmGx1/ON5VU9ZP00LFhBaHh+7JJ+WTaL5Rerq3pfFhdevvbNoR0/juUFvHTwwxUKFXYHxF/IFw7YvdyYpCT8uVB2NtrSiLMTWk2GaWPVStvmy0PPRLXRh82xo4LtxUU+roKgg4EDrT76bYI6aneemM2qiRBo5DXsFSN/Z22c="
Aug 23 14:20:16 volumio go-librespot[25013]: time="2024-08-23T14:20:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:17 volumio go-librespot[25013]: time="2024-08-23T14:20:17+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:17 volumio go-librespot[25013]: time="2024-08-23T14:20:17+02:00" level=debug msg="completed challenge"
Aug 23 14:20:17 volumio go-librespot[25013]: time="2024-08-23T14:20:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:17 volumio volumio[795]: info: Connection to go-librespot Websocket closed
Aug 23 14:20:19 volumio volumio[795]: info: Getting Spotify volume
Aug 23 14:20:19 volumio volumio[795]: (node:795) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:19 volumio volumio[795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Aug 23 14:20:19 volumio volumio[795]: (node:795) 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: 30151)
Aug 23 14:20:19 volumio volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Aug 23 14:20:19 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:19 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:19 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:20 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:20 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94785.
Aug 23 14:20:20 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:20 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:20 volumio go-librespot[25021]: Librespot-go daemon starting...
Aug 23 14:20:20 volumio go-librespot[25021]: time="2024-08-23T14:20:20+02:00" level=info msg="generated new device id: eedc80c515940666352d14fde04005c7d7c06657"
Aug 23 14:20:20 volumio go-librespot[25021]: time="2024-08-23T14:20:20+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:21 volumio go-librespot[25021]: time="2024-08-23T14:20:21+02:00" level=debug msg="obtained new client token: AAAC/lo1xKqDJ/ipoHaK0aNrpyqWjWNhdSfDpziVM85ZKMpVnGNnZiR7J63LxAfZ+UE3CP0wrsZW/F276ekBXWWrJXb3ckIjTNNnpiXAnSbU8qQRiJkHWpoywzWjMq/Go1YgrWrezYWqzaP2iDhJ/Xc50hqrxs/1O8UK2cImBA76pYzS7UCv8KO5YWy+qZE13GX7RN5zGgYnTfy4lb+9h/qbxxOAW2z4Vm8E4qzY46mTe+MsPmRu1Ce4lp/v"
Aug 23 14:20:21 volumio go-librespot[25021]: time="2024-08-23T14:20:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:21 volumio go-librespot[25021]: time="2024-08-23T14:20:21+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:22 volumio go-librespot[25021]: time="2024-08-23T14:20:22+02:00" level=debug msg="completed challenge"
Aug 23 14:20:22 volumio go-librespot[25021]: time="2024-08-23T14:20:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:23 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:23 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94786.
Aug 23 14:20:25 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:25 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:25 volumio go-librespot[25030]: Librespot-go daemon starting...
Aug 23 14:20:25 volumio go-librespot[25030]: time="2024-08-23T14:20:25+02:00" level=info msg="generated new device id: 9689ae91230a520a816724b5abc35c7ae129a672"
Aug 23 14:20:25 volumio go-librespot[25030]: time="2024-08-23T14:20:25+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:25 volumio go-librespot[25030]: time="2024-08-23T14:20:25+02:00" level=debug msg="obtained new client token: AABT3QrJt1hgcB1poXEMvTKx14gud6utlKEf/Jfi6TmRWH/eFml0piOGNsQ1mw3BSc7/eDK9qYwXxQJgOxYMmYaCTbc9RjLcqdUmM5P6AWNmeewBK1rYPjym6mt6fUqmr3A9KKz9a+HHdM1mhO7449lr648WpqZoqW2oa75wG/mn27561JhorlmTq/ol0OwLeVUH8XuQTmLOJso3bu6REgtxTF6PjdowwECWxw2iswxzweRcwuJePi5UQe94syc="
Aug 23 14:20:25 volumio go-librespot[25030]: time="2024-08-23T14:20:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:25 volumio go-librespot[25030]: time="2024-08-23T14:20:25+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:26 volumio go-librespot[25030]: time="2024-08-23T14:20:26+02:00" level=debug msg="completed challenge"
Aug 23 14:20:26 volumio go-librespot[25030]: time="2024-08-23T14:20:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:26 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:26 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94787.
Aug 23 14:20:29 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:29 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:29 volumio go-librespot[25038]: Librespot-go daemon starting...
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=info msg="generated new device id: 2c0a73ee33c910b73cc46f2033adf77db1c63d7d"
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:29 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:29 volumio volumio[795]: info: Connection to go-librespot Websocket established
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=debug msg="new websocket client"
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=debug msg="obtained new client token: AACuTDcu39qRxz6xPvriwThvJpjTBEd3rrAVROBlUD8x7gN5++PGa1JVnH0B9LhOIqqNTVy2ju6eQnCxSD3mPcK0fyBL9Uyu+glly/mM2ptZ1ElyIzfn2vLF/95J+37PbHgJjUtIzQaJm3rZUKFnOiP7kr2ZA4x+g7k63ahVvsKuQlNQjlYELLXsqGnmCZlz2cooKcBDB0h+8YOOJPtb3Radbi9UvYNyUIEoJkhAVb5MrOkksVx/KTqYsOFAxwg="
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:29 volumio go-librespot[25038]: time="2024-08-23T14:20:29+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:30 volumio go-librespot[25038]: time="2024-08-23T14:20:30+02:00" level=debug msg="completed challenge"
Aug 23 14:20:30 volumio go-librespot[25038]: time="2024-08-23T14:20:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:30 volumio volumio[795]: info: Connection to go-librespot Websocket closed
Aug 23 14:20:32 volumio volumio[795]: info: Getting Spotify volume
Aug 23 14:20:32 volumio volumio[795]: (node:795) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:32 volumio volumio[795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Aug 23 14:20:32 volumio volumio[795]: (node:795) 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: 30152)
Aug 23 14:20:32 volumio volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Aug 23 14:20:32 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:32 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:32 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:33 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:33 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94788.
Aug 23 14:20:33 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:33 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:33 volumio go-librespot[25046]: Librespot-go daemon starting...
Aug 23 14:20:33 volumio go-librespot[25046]: time="2024-08-23T14:20:33+02:00" level=info msg="generated new device id: 603e95674ffe7363ecce3fded749aa3665b6ada6"
Aug 23 14:20:33 volumio go-librespot[25046]: time="2024-08-23T14:20:33+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:33 volumio go-librespot[25046]: time="2024-08-23T14:20:33+02:00" level=debug msg="obtained new client token: AACPefXBGbAgDQhs3c/sJiF+knLMjehQGTNGq1499T2sPLBTht7ngP84p0GKsRfoOdbnBLd65uQzGGZPKEPjiNUBfHHCfoV1unctB6XEiXMW5YXhkYBHzxD3Pg7CZwmXEY/+RObYwNv0TX//AavW6jr096seXdJHycrVI+tUMK1xD/RtpVGJPotkJeCszXRuHZjDN3fWlSw1SaqPtlqB2j4vyLKF8iNx7eU6/UR2ws59DA2mBMI/Xt6o1NtOo7I="
Aug 23 14:20:34 volumio go-librespot[25046]: time="2024-08-23T14:20:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:34 volumio go-librespot[25046]: time="2024-08-23T14:20:34+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:34 volumio go-librespot[25046]: time="2024-08-23T14:20:34+02:00" level=debug msg="completed challenge"
Aug 23 14:20:34 volumio go-librespot[25046]: time="2024-08-23T14:20:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:36 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:36 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94789.
Aug 23 14:20:37 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:37 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:37 volumio go-librespot[25055]: Librespot-go daemon starting...
Aug 23 14:20:37 volumio go-librespot[25055]: time="2024-08-23T14:20:37+02:00" level=info msg="generated new device id: 830df809b3be8a1d8632914c83e7e28d9fedb7c5"
Aug 23 14:20:37 volumio go-librespot[25055]: time="2024-08-23T14:20:37+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:38 volumio go-librespot[25055]: time="2024-08-23T14:20:38+02:00" level=debug msg="obtained new client token: AACuWpkWEL70Jf3Y2SCXRJt9bWnQAjC3JbPUX5dszcd+oTePpLn7Cqh+gqXPtgmo7sbp8DdiZf6rFgb35JueNXR1UFp+xMlySzIzwToVfAfSdrsXnyC4Iqh58nJiwbVWjA/gSBf2phagvBI6OA58up4x+m+NLIKYvGJjMv99qKv+T/R+b3o7t1TBhxJZaXoNCru++e7QXz7B8phH1dYJ+r0Pf+g7IPRiu2Vf4FRYvflz4jMo8TAkKdfL3jG0"
Aug 23 14:20:38 volumio go-librespot[25055]: time="2024-08-23T14:20:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:38 volumio go-librespot[25055]: time="2024-08-23T14:20:38+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:39 volumio go-librespot[25055]: time="2024-08-23T14:20:39+02:00" level=debug msg="completed challenge"
Aug 23 14:20:39 volumio go-librespot[25055]: time="2024-08-23T14:20:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:39 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:39 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:42 volumio volumio[795]: info: Clearing queue after UPNP request
Aug 23 14:20:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94790.
Aug 23 14:20:42 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:42 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:42 volumio go-librespot[25065]: Librespot-go daemon starting...
Aug 23 14:20:42 volumio volumio[795]: info: CoreStateMachine::ClearQueue
Aug 23 14:20:42 volumio volumio[795]: info: CoreStateMachine::stop
Aug 23 14:20:42 volumio volumio[795]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 23 14:20:42 volumio volumio[795]: info: CorePlayQueue::clearPlayQueue
Aug 23 14:20:42 volumio volumio[795]: info: CorePlayQueue::saveQueue
Aug 23 14:20:42 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:42 volumio go-librespot[25065]: time="2024-08-23T14:20:42+02:00" level=info msg="generated new device id: 940278356dff89a0aef8c6cefac1096c6e455350"
Aug 23 14:20:42 volumio go-librespot[25065]: time="2024-08-23T14:20:42+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:42 volumio volumio[795]: info: CoreCommandRouter::volumioPushQueue
Aug 23 14:20:42 volumio volumio[795]: error: Upnp client error: Error: This socket has been ended by the other party
Aug 23 14:20:42 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:42 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:42 volumio go-librespot[25065]: time="2024-08-23T14:20:42+02:00" level=debug msg="new websocket client"
Aug 23 14:20:42 volumio volumio[795]: info: Connection to go-librespot Websocket established
Aug 23 14:20:42 volumio go-librespot[25065]: time="2024-08-23T14:20:42+02:00" level=debug msg="obtained new client token: AACAjn/bmXQbwfx4ynK53t1AEkesrhwoYGkAy5OXwt+QgWrAiXQglobEYMx4MK3aqHbbmXV1PhwngLrrwvRv2q4ogMYfu9mwXix07KVP7Z6RpQOkqsDXdjS9Xh0Ph6NMndrq1kf78n9yLukaHufKkkBT6pRfgOYhHEiX+WXD/lHYoY/tXQS2/5WY2gqLuZdhdJRwzSF3/wIqeLIuQvYoO5g28rW6U9CzPBjegE3q8JhlGfWAU6qXvHYg6iG2/yM="
Aug 23 14:20:42 volumio go-librespot[25065]: time="2024-08-23T14:20:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:43 volumio go-librespot[25065]: time="2024-08-23T14:20:43+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:43 volumio go-librespot[25065]: time="2024-08-23T14:20:43+02:00" level=debug msg="completed challenge"
Aug 23 14:20:43 volumio go-librespot[25065]: time="2024-08-23T14:20:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:43 volumio volumio[795]: info: Connection to go-librespot Websocket closed
Aug 23 14:20:44 volumio volumio[795]: info: Starting UPNP Playback
Aug 23 14:20:44 volumio volumio[795]: info: Preparing playback through UPNP
Aug 23 14:20:44 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:44 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:44 volumio volumio[795]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 14ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 9ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 8ms
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 21ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 19ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 18ms
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 2ms
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info:
Aug 23 14:20:44 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:44 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:44 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:45 volumio volumio[795]: info: Getting Spotify volume
Aug 23 14:20:45 volumio volumio[795]: (node:795) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:45 volumio volumio[795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Aug 23 14:20:45 volumio volumio[795]: (node:795) 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: 30153)
Aug 23 14:20:45 volumio volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:45 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 16 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 14 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 12 milliseconds
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:45 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 13 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 10 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 10 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 10 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 9 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 8 milliseconds
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus stop
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 82ms
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 75ms
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 74ms
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:45 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:45 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:45 volumio volumio[795]: info:
Aug 23 14:20:45 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 58ms
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 56 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 49 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 44 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 43 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 7ms
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 6 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand status took 4 milliseconds
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":813,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 93ms
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 84ms
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 83ms
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 22 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 20 milliseconds
Aug 23 14:20:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 20 milliseconds
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:45 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":1,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rmf_classic","artist":"RMF Classic","album":null,"uri":"http://217.74.72.10:8000/rmf_classic","trackType":""}
Aug 23 14:20:45 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:45 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:45 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 93ms
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 40ms
Aug 23 14:20:45 volumio volumio[795]: info: ------------------------------ 38ms
Aug 23 14:20:46 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:46 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94791.
Aug 23 14:20:46 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:46 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:46 volumio go-librespot[25123]: Librespot-go daemon starting...
Aug 23 14:20:46 volumio go-librespot[25123]: time="2024-08-23T14:20:46+02:00" level=info msg="generated new device id: cef0ee68add97abcb332944b6220c4aedf3b8221"
Aug 23 14:20:46 volumio go-librespot[25123]: time="2024-08-23T14:20:46+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:46 volumio go-librespot[25123]: time="2024-08-23T14:20:46+02:00" level=debug msg="obtained new client token: AACmDeMWNykG6sVdIjvYjV8SM8865hW49OnPMIEu0qkV7AYtV/asFPEAsAtDHgEE2CL/Ik1jud/C+JKl6vkupnaqv8q/95o2NZeiTM4IIxZsGmGgxPcR2fogC6PKiudf8pB/sgpl5T+6SatNAmJQcrBFDnqOkYqmLAsULh6h1n7XWm1mfQwX9XrYIdcGfHhJqv2bTS6ZJfFk5CoCtFJDU+nllCjxSu9IuRMtkZp/myMWyvGzOJShyOyCFmNJJpU="
Aug 23 14:20:47 volumio go-librespot[25123]: time="2024-08-23T14:20:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:47 volumio go-librespot[25123]: time="2024-08-23T14:20:47+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:47 volumio go-librespot[25123]: time="2024-08-23T14:20:47+02:00" level=debug msg="completed challenge"
Aug 23 14:20:47 volumio go-librespot[25123]: time="2024-08-23T14:20:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:49 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:49 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94792.
Aug 23 14:20:50 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:50 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:50 volumio go-librespot[25140]: Librespot-go daemon starting...
Aug 23 14:20:50 volumio go-librespot[25140]: time="2024-08-23T14:20:50+02:00" level=info msg="generated new device id: e5abab5a32072f1ca2bb80a70de0202dfe158890"
Aug 23 14:20:50 volumio go-librespot[25140]: time="2024-08-23T14:20:50+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:51 volumio go-librespot[25140]: time="2024-08-23T14:20:51+02:00" level=debug msg="obtained new client token: AADSbu9ltGFg6zK1YM9j9VFismNmbHH2jJ1g1LHY8LoqGUVTTqwujHJ50MIKu4QcAd1ew6Ih5nMlPVYtbcHEI+UmPdmPgd/cFudg7T0s4+DTISIAB+oP3Yiv0EB7T4kfs28zN223JSqw+rg7fnN4K5FTOticnVSZIeMv2y74LmcN/CgG6vTKJ7YHPWd7rLEBsg5FGr/9tRgJxVMXCJUseU9HyBoKQzT48eWJ+gnIOoivg5O1CL+agEOXZR5Wtoc="
Aug 23 14:20:51 volumio go-librespot[25140]: time="2024-08-23T14:20:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:20:51 volumio go-librespot[25140]: time="2024-08-23T14:20:51+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand status took 5 milliseconds
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand status took 3 milliseconds
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand status took 3 milliseconds
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand playlistinfo took 3 milliseconds
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand playlistinfo took 2 milliseconds
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand playlistinfo took 2 milliseconds
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:52 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:52 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 17ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 16ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 14ms
Aug 23 14:20:52 volumio volumio[795]: info: Clearing queue after UPNP request
Aug 23 14:20:52 volumio go-librespot[25140]: time="2024-08-23T14:20:52+02:00" level=debug msg="completed challenge"
Aug 23 14:20:52 volumio go-librespot[25140]: time="2024-08-23T14:20:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::ClearQueue
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::stop
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::stPlaybackTimer
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::updateTrackBlock
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrackBlock
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::serviceStop
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: info: ControllerMpd::stop
Aug 23 14:20:52 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand stop
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::clearPlayQueue
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::saveQueue
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::volumioPushQueue
Aug 23 14:20:52 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:52 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: sendMpdCommand stop took 25 milliseconds
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: error: updateQueue error: null
Aug 23 14:20:52 volumio volumio[795]: error: updateQueue error: null
Aug 23 14:20:52 volumio volumio[795]: error: updateQueue error: null
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info: Starting UPNP Playback
Aug 23 14:20:52 volumio volumio[795]: info: Preparing playback through UPNP
Aug 23 14:20:52 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:52 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:52 volumio volumio[795]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 23 14:20:52 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:52 volumio volumio[795]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 5ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info:
Aug 23 14:20:52 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:52 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 6ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 4ms
Aug 23 14:20:52 volumio volumio[795]: info: ------------------------------ 3ms
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 26 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 26 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 26 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 3 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 2 milliseconds
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nazywali go Marynarz - szanta narciarska","artist":"Artur Andrus","album":"Cyniczne córy Zurychu","uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus stop
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 35ms
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 9 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 10 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 10 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 5 milliseconds
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nazywali go Marynarz - szanta narciarska","artist":"Artur Andrus","album":"Cyniczne córy Zurychu","uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nazywali go Marynarz - szanta narciarska","artist":"Artur Andrus","album":"Cyniczne córy Zurychu","uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nazywali go Marynarz - szanta narciarska","artist":"Artur Andrus","album":"Cyniczne córy Zurychu","uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::volumioPushState
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 87ms
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 87ms
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 64ms
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:55 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 55 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 48 milliseconds
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","artist":"Music Assistant","album":null,"uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:55 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 63ms
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:55 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces system playlist update
Aug 23 14:20:55 volumio volumio[795]: info: Ignoring MPD Status Update
Aug 23 14:20:55 volumio volumio[795]: info:
Aug 23 14:20:55 volumio volumio[795]: ---------------------------- MPD announces state update: player
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::getState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 33ms
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 33 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 32 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 27ms
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 27 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 26ms
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand status took 25 milliseconds
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseState
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","artist":"Music Assistant","album":null,"uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:55 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 96ms
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 8 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 7 milliseconds
Aug 23 14:20:55 volumio volumio[795]: info: sendMpdCommand playlistinfo took 7 milliseconds
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"618 Kbps","isStreaming":false,"title":"b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","artist":"Music Assistant","album":null,"uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"618 Kbps","isStreaming":false,"title":"b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","artist":"Music Assistant","album":null,"uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:55 volumio volumio[795]: info: ControllerMpd::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::servicePushState
Aug 23 14:20:55 volumio volumio[795]: verbose: In UPNP mode
Aug 23 14:20:55 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"618 Kbps","isStreaming":false,"title":"b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","artist":"Music Assistant","album":null,"uri":"http://192.168.178.47:8097/single/uuid:548a2cb5-2925-37ea-1818-d83addd4d894/b3de4ff289374176bf29ae19fce78c3b.flac?ts=1724415651","trackType":""}
Aug 23 14:20:55 volumio volumio[795]: verbose: CURRENT POSITION 3
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState stateService play
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play
Aug 23 14:20:55 volumio volumio[795]: info: Received an update from plugin. extracting info from payload
Aug 23 14:20:55 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Aug 23 14:20:55 volumio volumio[795]: info: CoreStateMachine::pushState
Aug 23 14:20:55 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:55 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:55 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:55 volumio volumio[795]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 54ms
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 47ms
Aug 23 14:20:55 volumio volumio[795]: info: ------------------------------ 45ms
Aug 23 14:20:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94793.
Aug 23 14:20:55 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:55 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:55 volumio go-librespot[25167]: Librespot-go daemon starting...
Aug 23 14:20:55 volumio go-librespot[25167]: time="2024-08-23T14:20:55+02:00" level=info msg="generated new device id: 1a62d0227e9d42b837f0b795125b206eaeed4df7"
Aug 23 14:20:55 volumio go-librespot[25167]: time="2024-08-23T14:20:55+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:20:55 volumio volumio[795]: info: Initializing connection to go-librespot Websocket
Aug 23 14:20:55 volumio go-librespot[25167]: time="2024-08-23T14:20:55+02:00" level=debug msg="new websocket client"
Aug 23 14:20:55 volumio volumio[795]: info: Connection to go-librespot Websocket established
Aug 23 14:20:55 volumio go-librespot[25167]: time="2024-08-23T14:20:55+02:00" level=debug msg="obtained new client token: AACAuS6uRx9HU5nXZGdDLDyiDJQlce4E0Wrz9jGpZIYJSDie1wPwxj8jbCxAVmYx98xa44l6NF1gbSnUPEBdx+FCAk57mBNus1A5NYC+DLsgkppJSwViKytV0VcaoornJDDeQu1eYd8suoNmbo3b1VHoJkZW6J+SIWnCV8RiiHeDX71gDkGRjAHLxHfCYRz/97QFW9J12vCBejnOXvdkFR88kIPOatZoFbk+Mg7Rz6BlYuduTpnEgWPPlezXqfk="
Aug 23 14:20:56 volumio go-librespot[25167]: time="2024-08-23T14:20:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 23 14:20:56 volumio go-librespot[25167]: time="2024-08-23T14:20:56+02:00" level=debug msg="completed keyexchange"
Aug 23 14:20:56 volumio go-librespot[25167]: time="2024-08-23T14:20:56+02:00" level=debug msg="completed challenge"
Aug 23 14:20:56 volumio go-librespot[25167]: time="2024-08-23T14:20:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:20:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:20:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:20:56 volumio volumio[795]: info: Connection to go-librespot Websocket closed
Aug 23 14:20:58 volumio volumio[795]: info: Getting Spotify volume
Aug 23 14:20:58 volumio volumio[795]: (node:795) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 23 14:20:58 volumio volumio[795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Aug 23 14:20:58 volumio volumio[795]: (node:795) 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: 30154)
Aug 23 14:20:58 volumio volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Aug 23 14:20:58 volumio volumio[795]: info: CoreCommandRouter::volumioGetState
Aug 23 14:20:58 volumio volumio[795]: info: CorePlayQueue::getTrack 3
Aug 23 14:20:58 volumio volumio[795]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 23 14:20:58 volumio volumio[795]: TypeError: Cannot read property 'name' of undefined
Aug 23 14:20:58 volumio volumio[795]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48)
Aug 23 14:20:58 volumio volumio[795]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28)
Aug 23 14:20:58 volumio volumio[795]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:53:38)
Aug 23 14:20:58 volumio volumio[795]: at Socket.emit (events.js:315:20)
Aug 23 14:20:58 volumio volumio[795]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Aug 23 14:20:58 volumio volumio[795]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Aug 23 14:20:58 volumio volumio[795]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 23 14:20:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Aug 23 14:20:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94794.
Aug 23 14:20:59 volumio systemd[1]: Stopped go-librespot Daemon.
Aug 23 14:20:59 volumio systemd[1]: Started go-librespot Daemon.
Aug 23 14:20:59 volumio go-librespot[25190]: Librespot-go daemon starting...
Aug 23 14:20:59 volumio go-librespot[25190]: time="2024-08-23T14:20:59+02:00" level=info msg="generated new device id: 9438c3b6ca28d7cf0693dca59a989b791997b9d5"
Aug 23 14:20:59 volumio go-librespot[25190]: time="2024-08-23T14:20:59+02:00" level=debug msg="stored credentials found for 6wnwbcx8fzn7amyf8ck51bjmi"
Aug 23 14:21:00 volumio go-librespot[25190]: time="2024-08-23T14:21:00+02:00" level=debug msg="obtained new client token: AABTUu/E4haxoYbwzbY/NBevat5xjxSNw2HvMUbEAp+DL/RvRx+XDPpYz4+lMWy9w7HsB05Rl7wgTHETk1KutxWLvKUwJmhBnjy4MC3sZDEon+iYlVB0GkG7sEIqQeIvOUznBtDF2NbXPUaFvF/1jSwfHvAsy9K2NfmMQQg+u0m+XGgiQC/X0ZfNEpr//rOVurEXzszpz7ONDOh/pnk5TsmyKqpEvsUtpmZzP+1z+hdrLWpjQ2SayNd5YXwq1pk="
Aug 23 14:21:00 volumio go-librespot[25190]: time="2024-08-23T14:21:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 23 14:21:00 volumio go-librespot[25190]: time="2024-08-23T14:21:00+02:00" level=debug msg="completed keyexchange"
Aug 23 14:21:01 volumio go-librespot[25190]: time="2024-08-23T14:21:01+02:00" level=debug msg="completed challenge"
Aug 23 14:21:01 volumio go-librespot[25190]: time="2024-08-23T14:21:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials"
Aug 23 14:21:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 23 14:21:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 23 14:21:01 volumio sudo[25201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 14:20
Aug 23 14:21:01 volumio sudo[25201]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET"
VOLUMIO_VERSION="3.616"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"