-- Logs begin at Thu 2024-10-03 11:32:59 BST, end at Thu 2024-10-03 11:54:00 BST. -- Oct 03 11:53:00 volumio-office go-librespot[4899]: time="2024-10-03T11:53:00+01:00" level=debug msg="completed challenge" Oct 03 11:53:00 volumio-office go-librespot[4899]: time="2024-10-03T11:53:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:00 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:00 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:01 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:01 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:03 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:03 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 240. Oct 03 11:53:03 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:03 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:03 volumio-office go-librespot[4918]: Librespot-go daemon starting... Oct 03 11:53:03 volumio-office go-librespot[4918]: time="2024-10-03T11:53:03+01:00" level=info msg="generated new device id: 84434aa407b7beb007f4ef3b32ee10252e53a661" Oct 03 11:53:03 volumio-office go-librespot[4918]: time="2024-10-03T11:53:03+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:03 volumio-office go-librespot[4918]: time="2024-10-03T11:53:03+01:00" level=debug msg="obtained new client token: AADLN2CihASo3UvdpqcijbyRstiYKDtEFgoBVvnHGL00NvEgRHOgeGCkyD/qvjzqsPVftTSQyPc0HUcSqzz3NZAXuutPnPUHSEx10dLxPzrdg+FLob2XoTRaIXuDXJWB8mVwSGqlKiTC0VqaYGWNyLHs83ul7s42v5y7LqIt6R6mJbn3whtukfxaI7hPWmOxNuD5sBAw88tJcREYlSJHG4vsMKkYjvZqzm0EXyBbeDnAlq+Ho5BbGPgIQVgSEQ==" Oct 03 11:53:03 volumio-office go-librespot[4918]: time="2024-10-03T11:53:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 03 11:53:04 volumio-office go-librespot[4918]: time="2024-10-03T11:53:04+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:04 volumio-office go-librespot[4918]: time="2024-10-03T11:53:04+01:00" level=debug msg="completed challenge" Oct 03 11:53:04 volumio-office go-librespot[4918]: time="2024-10-03T11:53:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:04 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:04 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:04 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:04 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:07 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:07 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:07 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:07 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 241. Oct 03 11:53:07 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:07 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:07 volumio-office go-librespot[4927]: Librespot-go daemon starting... Oct 03 11:53:07 volumio-office go-librespot[4927]: time="2024-10-03T11:53:07+01:00" level=info msg="generated new device id: 355aafac32bdd6ddb3dd11e1ca4ab22c9ad921da" Oct 03 11:53:07 volumio-office go-librespot[4927]: time="2024-10-03T11:53:07+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:08 volumio-office go-librespot[4927]: time="2024-10-03T11:53:08+01:00" level=debug msg="obtained new client token: AABN+GGW4MH1lVpQVueIQZWj/OLJEwdSToVBGK1LE9GmqMK3OBzmv2jo0GcaEZkodRjPkzdtNl9bAQuD7hBiHwmd1PXIdmW5VEAzfsjAjre/qgcELveJuvEW44tRZaJ7jl1/q400X4uTvkjzYqBp1+nVHRZ6O1pHyWdEFNd3XgJbAiw3mlzzaVRJvFyj6pc44AeHMk1BvOZ3RY4ITrlBudLLJfPX/6WlvXTFeOm1dIX9RPsK1UKMdIcYJI2Q8Q==" Oct 03 11:53:08 volumio-office go-librespot[4927]: time="2024-10-03T11:53:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:08 volumio-office go-librespot[4927]: time="2024-10-03T11:53:08+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:09 volumio-office go-librespot[4927]: time="2024-10-03T11:53:09+01:00" level=debug msg="completed challenge" Oct 03 11:53:09 volumio-office go-librespot[4927]: time="2024-10-03T11:53:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:09 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:09 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:09 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:09 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:10 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:10 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:12 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:12 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Oct 03 11:53:12 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:12 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:12 volumio-office go-librespot[4985]: Librespot-go daemon starting... Oct 03 11:53:12 volumio-office go-librespot[4985]: time="2024-10-03T11:53:12+01:00" level=info msg="generated new device id: fa1c034d9ec2f4d132f3cfecde86429dae8fb4ab" Oct 03 11:53:12 volumio-office go-librespot[4985]: time="2024-10-03T11:53:12+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:12 volumio-office go-librespot[4985]: time="2024-10-03T11:53:12+01:00" level=debug msg="obtained new client token: AADOCqb1+5x0fCxVsVhEjJpDpyshIuR8HbAq3APUgqvgBcbfGisz2wyFw5qpaTR+Q2G7djU5s4Zuk5a/iFUvhiA/b6o4rV7yH/Dbz5L/28diRL1d1nRWV92rEtWHBiOyKSD75W0hHLRGfthxKh2RoQyFQr/VtdQq3fWgcfA0tTcX/KsmfzHHz0KG3k51CBymh+N6pFRuL6a1UwzJZ/JHBPWYo2Lr0g4/526xuza8ureK7z7Q4seDRnmfKNRx7w==" Oct 03 11:53:12 volumio-office go-librespot[4985]: time="2024-10-03T11:53:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:13 volumio-office go-librespot[4985]: time="2024-10-03T11:53:13+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:13 volumio-office go-librespot[4985]: time="2024-10-03T11:53:13+01:00" level=debug msg="completed challenge" Oct 03 11:53:13 volumio-office go-librespot[4985]: time="2024-10-03T11:53:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:13 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:13 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:13 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:13 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:16 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:16 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:16 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:16 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Oct 03 11:53:16 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:16 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:16 volumio-office go-librespot[4993]: Librespot-go daemon starting... Oct 03 11:53:16 volumio-office go-librespot[4993]: time="2024-10-03T11:53:16+01:00" level=info msg="generated new device id: d6d0b05d84ddeb77743d5da696383ce29015668c" Oct 03 11:53:16 volumio-office go-librespot[4993]: time="2024-10-03T11:53:16+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:17 volumio-office go-librespot[4993]: time="2024-10-03T11:53:17+01:00" level=debug msg="obtained new client token: AADyAiXoijxzeQLdaEM9la7Ljtiu5M7pjXQqR4mnNxAuMTlErvDKtOBk8dqRIM9V2bUCy3CesPlgy7xGHXUnynnIaDomxHXZf2l5l74Jc6nyxDH7lBh9Yy5COaISP5d11w3gjFytkGHXdDY7Qb8nmIbGY+6Gh0Z4qQZSCzRaqSjPWxU1s3WuiqNOdxtUqM1OVygCP52MwxXJtU9DZA3AhRJtGUpykQYFxryKUf0uDFd7TjU2Tqfmlxa9/WHTyA==" Oct 03 11:53:17 volumio-office go-librespot[4993]: time="2024-10-03T11:53:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:17 volumio-office go-librespot[4993]: time="2024-10-03T11:53:17+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:18 volumio-office go-librespot[4993]: time="2024-10-03T11:53:18+01:00" level=debug msg="completed challenge" Oct 03 11:53:18 volumio-office go-librespot[4993]: time="2024-10-03T11:53:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:18 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:18 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:19 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:19 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:19 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:19 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:19 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:19 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:21 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:21 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Oct 03 11:53:21 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:21 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:21 volumio-office go-librespot[5012]: Librespot-go daemon starting... Oct 03 11:53:21 volumio-office go-librespot[5012]: time="2024-10-03T11:53:21+01:00" level=info msg="generated new device id: ac8b05dfe9edf461586a4c8a8c047a57613715a4" Oct 03 11:53:21 volumio-office go-librespot[5012]: time="2024-10-03T11:53:21+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:21 volumio-office go-librespot[5012]: time="2024-10-03T11:53:21+01:00" level=debug msg="obtained new client token: AABnO3mhPN+tkD0fUuQLTZSywNDzPZxk8ghftV+YOTUa5Pv3bc17Q/7mihuyxcuxLyBeSFFCf4CjfPMfwfeoIZ1a3PHCW2MEPCfOChY2G/G5mxl7bjgtlryyYu37QLtZwXRnQjB+mrGsjJF2Xq1jTvvw1CITLz7XkBfmb4hYDsWtmdWsot1VCi8A9+c+ee05x2fDR1DYmihPmIIcCtyoiUCEl7P9FjlKugrOdXq+DUhN5vvl1UDTmGpd4JpTUQ==" Oct 03 11:53:21 volumio-office go-librespot[5012]: time="2024-10-03T11:53:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:22 volumio-office go-librespot[5012]: time="2024-10-03T11:53:22+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:22 volumio-office go-librespot[5012]: time="2024-10-03T11:53:22+01:00" level=debug msg="completed challenge" Oct 03 11:53:22 volumio-office go-librespot[5012]: time="2024-10-03T11:53:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:22 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:22 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:22 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:22 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:24 volumio-office volumio-remote-updater[488]: No test mode Oct 03 11:53:24 volumio-office volumio-remote-updater[488]: No alpha test mode Oct 03 11:53:24 volumio-office volumio[693]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 11:53:24 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 11:53:25 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:25 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:25 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:25 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Oct 03 11:53:25 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:25 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:25 volumio-office go-librespot[5022]: Librespot-go daemon starting... Oct 03 11:53:25 volumio-office go-librespot[5022]: time="2024-10-03T11:53:25+01:00" level=info msg="generated new device id: 8ce767f8d1b955f846a7289f881933a14d424330" Oct 03 11:53:25 volumio-office go-librespot[5022]: time="2024-10-03T11:53:25+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:26 volumio-office volumio[693]: info: CoreCommandRouter::Close All Modals sent Oct 03 11:53:26 volumio-office go-librespot[5022]: time="2024-10-03T11:53:26+01:00" level=debug msg="obtained new client token: AACh3OhzxpXyOZzjbU/kJcSRp34IXPAcbawjcAZe7wWxwPdMNW3+/p+UzwAPgfhvr11C7trBfdDAdPEetuMWTLrRcyoYs9gJbb26XmKUffZH6gvi2bM9SagUjKM6eWjSndACuwi0oKs5pLR4ituOvmdmWp9bRkhRJr4r71Ii2WMY0vFK2DTorxogBDuJdnK67B3+i/h8OS7oibsPaao69hKyhtDX7oPinOxm6UDA/mFmRrDoJGrbsP2Kycp1xg==" Oct 03 11:53:26 volumio-office go-librespot[5022]: time="2024-10-03T11:53:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:26 volumio-office go-librespot[5022]: time="2024-10-03T11:53:26+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:27 volumio-office go-librespot[5022]: time="2024-10-03T11:53:27+01:00" level=debug msg="completed challenge" Oct 03 11:53:27 volumio-office go-librespot[5022]: time="2024-10-03T11:53:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:27 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:27 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:28 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:28 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:29 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:29 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:30 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:30 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Oct 03 11:53:30 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:30 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:30 volumio-office go-librespot[5042]: Librespot-go daemon starting... Oct 03 11:53:30 volumio-office go-librespot[5042]: time="2024-10-03T11:53:30+01:00" level=info msg="generated new device id: ee69b861790847a0f9f78543c856f9783a1920f2" Oct 03 11:53:30 volumio-office go-librespot[5042]: time="2024-10-03T11:53:30+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:30 volumio-office go-librespot[5042]: time="2024-10-03T11:53:30+01:00" level=debug msg="obtained new client token: AAB+ZWcexF2X7pewuFO0FpqStsXIggex23NXoFkdc5fySZXiYi5qA/hjGNofLQeOJ+q7KIq8ahHjTOITejTwxrF5sq2u6TtTQGOVDRs4PyyeIhfGIwST6ffU03vVrscUUKYVmimkq7YA0qcGqRFXHT55jRCP5ze+FJMI5p5h2vlrJpihj3ViAr7Qmz91OhCPSpFsl1M6L9TKs9INhY4YvU6WmrjJ4B7sF03Ob75htTgfw3oE2VNXlkeQ2VYaAA==" Oct 03 11:53:31 volumio-office go-librespot[5042]: time="2024-10-03T11:53:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:31 volumio-office go-librespot[5042]: time="2024-10-03T11:53:31+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:31 volumio-office go-librespot[5042]: time="2024-10-03T11:53:31+01:00" level=debug msg="completed challenge" Oct 03 11:53:31 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:31 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:31 volumio-office go-librespot[5042]: time="2024-10-03T11:53:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:31 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:31 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:31 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:31 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:34 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:34 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:34 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:34 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 247. Oct 03 11:53:34 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:34 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:34 volumio-office go-librespot[5051]: Librespot-go daemon starting... Oct 03 11:53:34 volumio-office go-librespot[5051]: time="2024-10-03T11:53:34+01:00" level=info msg="generated new device id: 78fb0736a4643bb51df781cee48f18dc13855c21" Oct 03 11:53:34 volumio-office go-librespot[5051]: time="2024-10-03T11:53:34+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:35 volumio-office go-librespot[5051]: time="2024-10-03T11:53:35+01:00" level=debug msg="obtained new client token: AAAoXxAHDSyklCTg1s/1Vd3+3SNvCF+4CRMO2T0dmhK2wNtWNCIZp88lTCxYte+6vLy3gAgFHJlaeanFhugQZRX7pWr0bHq0A0bJylWJ3WH5EVC1SWh958ZQ4qh8bNln71WLK6iAT+rngbcuvJQ4lYUXfiONvbvDxkxaabvvrJ0SZWXmdLQrqtks7rcYFGeuU0wX066DZqlV6cBToRJfcMP6H8pXmziw5XR7qUxR7wg9A3Ast+NSLY38EzLM+g==" Oct 03 11:53:35 volumio-office go-librespot[5051]: time="2024-10-03T11:53:35+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 03 11:53:35 volumio-office go-librespot[5051]: time="2024-10-03T11:53:35+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:36 volumio-office go-librespot[5051]: time="2024-10-03T11:53:36+01:00" level=debug msg="completed challenge" Oct 03 11:53:36 volumio-office go-librespot[5051]: time="2024-10-03T11:53:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:36 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:36 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:37 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:37 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:39 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:39 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:39 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:39 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:39 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:39 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 248. Oct 03 11:53:39 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:39 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:39 volumio-office go-librespot[5060]: Librespot-go daemon starting... Oct 03 11:53:39 volumio-office go-librespot[5060]: time="2024-10-03T11:53:39+01:00" level=info msg="generated new device id: 9b8b857f1f1bf4aadd9f6737da11c6ccf4459590" Oct 03 11:53:39 volumio-office go-librespot[5060]: time="2024-10-03T11:53:39+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:39 volumio-office go-librespot[5060]: time="2024-10-03T11:53:39+01:00" level=debug msg="obtained new client token: AABu2NpAO517CfJ/uqbDuk+sEzwXflQMmJmgPr1CEu4uf/QSPv2UUp5ua+swNiV0XKEvGopgp/OhEVI4XoLwRi4+xn7mnxgAomLh+UnrxDu0RPej/T+6BeZaXyewPD+OlstN2SwI6ctcPcbRMcLmNtjuQCDpORSJvSaux5VkMsrg6To+Lgq5K1ja0uz8rF4KrDMi9H4iBUS0lVt4Ayl2f0s9c5EF5RD0pItPt+WFJEKxlFurmGkXWTvPFWCRsQ==" Oct 03 11:53:40 volumio-office go-librespot[5060]: time="2024-10-03T11:53:40+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:40 volumio-office go-librespot[5060]: time="2024-10-03T11:53:40+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:40 volumio-office go-librespot[5060]: time="2024-10-03T11:53:40+01:00" level=debug msg="completed challenge" Oct 03 11:53:40 volumio-office go-librespot[5060]: time="2024-10-03T11:53:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:40 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:40 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:40 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:40 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:43 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:43 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:43 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:43 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 249. Oct 03 11:53:43 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:43 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:43 volumio-office go-librespot[5080]: Librespot-go daemon starting... Oct 03 11:53:43 volumio-office go-librespot[5080]: time="2024-10-03T11:53:43+01:00" level=info msg="generated new device id: a1311f7a8a7cf4b740256c58dc2906df206c8b31" Oct 03 11:53:43 volumio-office go-librespot[5080]: time="2024-10-03T11:53:43+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:44 volumio-office go-librespot[5080]: time="2024-10-03T11:53:44+01:00" level=debug msg="obtained new client token: AAARuqbAPW/A6EtCTvFgmKcYp2oV0pLvNhQ9dUGH5jULKbVn3t8OzDyDzjP57yL7nAZdkQXGjuL0PensXlcpXWC9DXkg2jrO7HqIyMa99KB4TcH31W7HOH6aJsxnyM6L81/dmNnaCdBXs2H3uBLV6tmN4V46laBOcuNTMpt4RhDeeu6n3a8GHxQHhoVv0Lmv6CSGmmfhA3QHvWamiQMkCsJDysN+OmzuD7Jva8RCWBwHraSAEiLxHeUprqlFAg==" Oct 03 11:53:44 volumio-office go-librespot[5080]: time="2024-10-03T11:53:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:44 volumio-office go-librespot[5080]: time="2024-10-03T11:53:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Oct 03 11:53:44 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:44 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:46 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:46 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:47 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:47 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 250. Oct 03 11:53:47 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:47 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:47 volumio-office go-librespot[5088]: Librespot-go daemon starting... Oct 03 11:53:47 volumio-office go-librespot[5088]: time="2024-10-03T11:53:47+01:00" level=info msg="generated new device id: da8a3ccbbcb49a68b35d64e767746156b7acdb7f" Oct 03 11:53:47 volumio-office go-librespot[5088]: time="2024-10-03T11:53:47+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:48 volumio-office go-librespot[5088]: time="2024-10-03T11:53:48+01:00" level=debug msg="obtained new client token: AAAEm/Sj6k+S4+Y/LCyfRYQIMhbDrLlnZOJIHQe5aa6IIxnavvpycNZvaSEPwpMndkJ95kApieL4pXqcDZpzgUfdMKEHdFCK6TjG1Bpy1oOf/s41/VBxajtHHVigK7ozH9J/rJPBHQnRSMHtOas3n6dk+7YmHynKfl0dCrAjBLUtRtz6RGTG06i3vy5Vskocf/oaLG0WeJ/3XwvcKBQiwN3ZDuDoEIpfE53uDc34bR2bQ7o755PNUxjqv2A=" Oct 03 11:53:48 volumio-office go-librespot[5088]: time="2024-10-03T11:53:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:48 volumio-office go-librespot[5088]: time="2024-10-03T11:53:48+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:48 volumio-office go-librespot[5088]: time="2024-10-03T11:53:48+01:00" level=debug msg="completed challenge" Oct 03 11:53:49 volumio-office go-librespot[5088]: time="2024-10-03T11:53:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:49 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:49 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:49 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:49 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:49 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:49 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:52 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:52 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 251. Oct 03 11:53:52 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:52 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:52 volumio-office go-librespot[5108]: Librespot-go daemon starting... Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=info msg="generated new device id: ca2ea2b91d192a90d6df771c5d091ff1f3db7c35" Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=debug msg="obtained new client token: AACSZRuLNVXmGgFx/VLURiDNiuNbvfQUjBSe8jDBVbC1pQG277Erg7aeow0EiwEghK/4tUd94/8FbJFgJzPpwPXWhFn5HGCbAYcrMB9Yn/xiYXYuCfWX4Gb3r4YQPDFEYRozB+ncNYKR7IvP32lYmEuFkvqORGh7XbJIvGVGxFR5m20lEUOX8Jq3E2lxK7LOBEr23LisIflm2ZqhLgnUCGfW1Sj43uLMEaC3FWe1dII2pORqojZzq5voOr1FvQ==" Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:52 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=debug msg="completed keyexchange" Oct 03 11:53:52 volumio-office go-librespot[5108]: time="2024-10-03T11:53:52+01:00" level=debug msg="new websocket client" Oct 03 11:53:52 volumio-office volumio[693]: info: Connection to go-librespot Websocket established Oct 03 11:53:53 volumio-office go-librespot[5108]: time="2024-10-03T11:53:53+01:00" level=debug msg="completed challenge" Oct 03 11:53:53 volumio-office go-librespot[5108]: time="2024-10-03T11:53:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 03 11:53:53 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:53 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:53 volumio-office volumio[693]: info: Connection to go-librespot Websocket closed Oct 03 11:53:54 volumio-office volumio[693]: info: Clearing queue after UPNP request Oct 03 11:53:54 volumio-office volumio[693]: info: CoreStateMachine::ClearQueue Oct 03 11:53:54 volumio-office volumio[693]: info: CoreStateMachine::stop Oct 03 11:53:54 volumio-office volumio[693]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 11:53:54 volumio-office volumio[693]: info: CorePlayQueue::clearPlayQueue Oct 03 11:53:54 volumio-office volumio[693]: info: CorePlayQueue::saveQueue Oct 03 11:53:54 volumio-office volumio[693]: info: CoreCommandRouter::volumioPushState Oct 03 11:53:54 volumio-office volumio[693]: info: CoreCommandRouter::volumioPushQueue Oct 03 11:53:55 volumio-office volumio[693]: error: Upnp client error: Error: This socket has been ended by the other party Oct 03 11:53:55 volumio-office volumio[693]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Oct 03 11:53:55 volumio-office volumio[693]: info: Getting Spotify volume Oct 03 11:53:55 volumio-office volumio[693]: (node:693) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:55 volumio-office volumio[693]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 03 11:53:55 volumio-office volumio[693]: (node:693) 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: 29) Oct 03 11:53:55 volumio-office volumio[693]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 03 11:53:55 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:55 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:55 volumio-office volumio[693]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Oct 03 11:53:56 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:56 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:56 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:53:56 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 252. Oct 03 11:53:56 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:53:56 volumio-office volumio[693]: info: Starting UPNP Playback Oct 03 11:53:56 volumio-office volumio[693]: info: Preparing playback through UPNP Oct 03 11:53:56 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:56 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:56 volumio-office volumio[693]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 11:53:56 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:53:56 volumio-office go-librespot[5116]: Librespot-go daemon starting... Oct 03 11:53:56 volumio-office go-librespot[5116]: time="2024-10-03T11:53:56+01:00" level=info msg="generated new device id: af837854af8b15b095f06fdbf8cdb069023ee92b" Oct 03 11:53:56 volumio-office go-librespot[5116]: time="2024-10-03T11:53:56+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:53:57 volumio-office go-librespot[5116]: time="2024-10-03T11:53:57+01:00" level=debug msg="obtained new client token: AAABs3OBROaF+vxFOTnmabQ031B76V38AvQne2uWzoJuogGU7vyTfHqpxoRkAbscbtlSuP+rU69/iudiaDY+tk2Dnofqe1BDUbkvdp7DSNpGDnzDYd6ch0bX/0NfgDY6tKnXf89oESEvVHPotQqhrvgksVqdKeKRbESMP2AiJv3dOWe0c/66juao4oCLVPOMWhvXBOZArgfpTMS/oITcPfeZqnk1iyx4P3/P5LPwuRynpWg3ok1wsWYtDSQ=" Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 25ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 15ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 13ms Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office go-librespot[5116]: time="2024-10-03T11:53:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 31ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 12ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 10ms Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 16ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 12ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 9ms Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 11ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 8ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 6ms Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office go-librespot[5116]: time="2024-10-03T11:53:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 13ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 9ms Oct 03 11:53:57 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 13ms Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: Oct 03 11:53:57 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:57 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 12ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 9ms Oct 03 11:53:57 volumio-office volumio[693]: info: ------------------------------ 6ms Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:58 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 28 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 22 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 18 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:58 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces system playlist update Oct 03 11:53:58 volumio-office volumio[693]: info: Ignoring MPD Status Update Oct 03 11:53:58 volumio-office volumio[693]: info: Oct 03 11:53:58 volumio-office volumio[693]: ---------------------------- MPD announces state update: player Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::getState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand status Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 68ms Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 57 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 41ms Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: 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":"394 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus stop Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"402 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus play Oct 03 11:53:58 volumio-office volumio[693]: info: Received an update from plugin. extracting info from payload Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"383 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus play Oct 03 11:53:58 volumio-office volumio[693]: info: Received an update from plugin. extracting info from payload Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 155ms Oct 03 11:53:58 volumio-office volumio[693]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:58 volumio-office volumio[693]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 144ms Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 135ms Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 76 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 74ms Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand status took 71 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseState Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus play Oct 03 11:53:58 volumio-office volumio[693]: info: Received an update from plugin. extracting info from payload Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:58 volumio-office volumio[693]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 146ms Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: verbose: ControllerMpd::parseTrackInfo Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus play Oct 03 11:53:58 volumio-office volumio[693]: info: Received an update from plugin. extracting info from payload Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:58 volumio-office volumio[693]: info: ControllerMpd::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::servicePushState Oct 03 11:53:58 volumio-office volumio[693]: verbose: In UPNP mode Oct 03 11:53:58 volumio-office volumio[693]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","artist":"Chase & Status/Stormzy - BACKBONE","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/4347ce9bf74749e4a54d5a5b7327d03d.flac?ts=1727952834","trackType":""} Oct 03 11:53:58 volumio-office volumio[693]: verbose: CURRENT POSITION 0 Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState stateService play Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::syncState currentStatus play Oct 03 11:53:58 volumio-office volumio[693]: info: Received an update from plugin. extracting info from payload Oct 03 11:53:58 volumio-office volumio[693]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 11:53:58 volumio-office volumio[693]: info: CoreStateMachine::pushState Oct 03 11:53:58 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:58 volumio-office volumio[693]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:58 volumio-office volumio[693]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 137ms Oct 03 11:53:58 volumio-office volumio[693]: info: ------------------------------ 112ms Oct 03 11:53:59 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:59 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:59 volumio-office volumio[693]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:59 volumio-office volumio[693]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 03 11:53:59 volumio-office volumio[693]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 03 11:53:59 volumio-office volumio[693]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 03 11:53:59 volumio-office volumio[693]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 03 11:53:59 volumio-office volumio[693]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 03 11:53:59 volumio-office volumio[693]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 03 11:53:59 volumio-office volumio[693]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 03 11:53:59 volumio-office volumio[693]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 03 11:53:59 volumio-office volumio[693]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 03 11:53:59 volumio-office volumio[693]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 03 11:53:59 volumio-office volumio[693]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 03 11:53:59 volumio-office volumio[693]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 03 11:53:59 volumio-office volumio[693]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 03 11:53:59 volumio-office volumio[693]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 03 11:53:59 volumio-office volumio[693]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 03 11:53:59 volumio-office volumio[693]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 03 11:53:59 volumio-office volumio[693]: info: Initializing connection to go-librespot Websocket Oct 03 11:53:59 volumio-office volumio[693]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 03 11:53:59 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:59 volumio-office volumio[693]: info: Listing playlists Oct 03 11:53:59 volumio-office volumio[693]: info: Starting UPNP Playback Oct 03 11:53:59 volumio-office volumio[693]: info: Preparing playback through UPNP Oct 03 11:53:59 volumio-office volumio[693]: info: Consume mode Oct 03 11:53:59 volumio-office volumio[693]: info: CoreCommandRouter::volumioGetState Oct 03 11:53:59 volumio-office volumio[693]: info: CorePlayQueue::getTrack 0 Oct 03 11:53:59 volumio-office volumio[693]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 11:53:59 volumio-office volumio[693]: TypeError: Cannot read property 'name' of undefined Oct 03 11:53:59 volumio-office volumio[693]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 03 11:53:59 volumio-office volumio[693]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 03 11:53:59 volumio-office volumio[693]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Oct 03 11:53:59 volumio-office volumio[693]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Oct 03 11:53:59 volumio-office volumio[693]: at Socket.emit (events.js:315:20) Oct 03 11:53:59 volumio-office volumio[693]: at addChunk (internal/streams/readable.js:309:12) Oct 03 11:53:59 volumio-office volumio[693]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 03 11:53:59 volumio-office volumio[693]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 03 11:53:59 volumio-office volumio[693]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Oct 03 11:53:59 volumio-office volumio[693]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 11:54:00 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 03 11:54:00 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Oct 03 11:54:00 volumio-office systemd[1]: Stopped go-librespot Daemon. Oct 03 11:54:00 volumio-office systemd[1]: Started go-librespot Daemon. Oct 03 11:54:00 volumio-office go-librespot[5150]: Librespot-go daemon starting... Oct 03 11:54:00 volumio-office go-librespot[5150]: time="2024-10-03T11:54:00+01:00" level=info msg="generated new device id: 95d342060883a2c5552957d6c6356d781a04283e" Oct 03 11:54:00 volumio-office go-librespot[5150]: time="2024-10-03T11:54:00+01:00" level=debug msg="stored credentials found for bignick8t3" Oct 03 11:54:00 volumio-office sudo[5158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 11:53 Oct 03 11:54:00 volumio-office sudo[5158]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"