-- Logs begin at Sun 2025-04-20 11:33:26 UTC, end at Sun 2025-04-20 14:01:41 UTC. -- Apr 20 14:00:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1787. Apr 20 14:00:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:00 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:00 volumio go-librespot[31415]: Librespot-go daemon starting... Apr 20 14:00:00 volumio go-librespot[31415]: time="2025-04-20T14:00:00Z" level=info msg="generated new device id: 4b6c293f2c9d85c05e196d7ec8a3836b7817336c" Apr 20 14:00:00 volumio go-librespot[31415]: time="2025-04-20T14:00:00Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:00 volumio go-librespot[31415]: time="2025-04-20T14:00:00Z" level=debug msg="obtained new client token: AAALZC7F0u1jx1eGfnQifqBQ6+l8feWruEIPjym6tjTm/dtGmEbryajku8f30Ed/INPpToeFnkZo6h0v6S+EXgR3uXt3RdTeIQaOoPgtH5sceF+ZwS9t5zll1bYZOaE1quCoAiOPRO2P6TUp16zCfL0jVdBCt/XITMC0T7RwKnOyHcwhpP9g0RSF6TCHyJ8jcmH7hfqosnXW9tjVvNeq1SzBYYBgHIQ9tO+IGnCd9rvmTvxISKoDIHrN0s6G7A==" Apr 20 14:00:01 volumio go-librespot[31415]: time="2025-04-20T14:00:01Z" 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]" Apr 20 14:00:01 volumio go-librespot[31415]: time="2025-04-20T14:00:01Z" level=debug msg="completed keyexchange" Apr 20 14:00:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:01 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:01 volumio go-librespot[31415]: time="2025-04-20T14:00:01Z" level=debug msg="completed challenge" Apr 20 14:00:01 volumio go-librespot[31415]: time="2025-04-20T14:00:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:02 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:02 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:03 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1788. Apr 20 14:00:04 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:04 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:04 volumio go-librespot[31422]: Librespot-go daemon starting... Apr 20 14:00:04 volumio go-librespot[31422]: time="2025-04-20T14:00:04Z" level=info msg="generated new device id: d449b226f065a859f82e359560aa4152e1f87542" Apr 20 14:00:04 volumio go-librespot[31422]: time="2025-04-20T14:00:04Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:04 volumio go-librespot[31422]: time="2025-04-20T14:00:04Z" level=debug msg="obtained new client token: AACM60VlHEtJwy/NZFyAjDWd17Ba9g1yepsZQOnKpFASM6duMCfIe/KjQO7qlziYtNkx3+Zl7Q070+e+Mm/c0v93HNa3ZhJk1YdPwe575JbUfKQPPFn7a4G3Q0v/8WDHn3Zw+ETDdkjyHMhYT6skPHR8c1/dQkfXCPY5cj+CMfb8goq78pblrkMpmskt8Gz8MAofYEh2BEaqB+ma0ji4iohFAdeFZyiv2Y0EmAsMtTjxb8Dh6iCPpKcbEB6l2w==" Apr 20 14:00:05 volumio go-librespot[31422]: time="2025-04-20T14:00:05Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:00:05 volumio go-librespot[31422]: time="2025-04-20T14:00:05Z" level=debug msg="completed keyexchange" Apr 20 14:00:05 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:05 volumio go-librespot[31422]: time="2025-04-20T14:00:05Z" level=debug msg="completed challenge" Apr 20 14:00:05 volumio go-librespot[31422]: time="2025-04-20T14:00:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:05 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:05 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:05 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:08 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:08 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:08 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1789. Apr 20 14:00:08 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:08 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:08 volumio go-librespot[31429]: Librespot-go daemon starting... Apr 20 14:00:08 volumio go-librespot[31429]: time="2025-04-20T14:00:08Z" level=info msg="generated new device id: d005b54a6eaa289bc650fa58d370645c6b2a1fb4" Apr 20 14:00:08 volumio go-librespot[31429]: time="2025-04-20T14:00:08Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:08 volumio go-librespot[31429]: time="2025-04-20T14:00:08Z" level=debug msg="obtained new client token: AAD/1K3+oy5S3396gUbJq+UjkOsy4/VwjYqDxgxoCRYxl8EzrbmIab7jpVMehTsl5P2myKvXjUKt67856tA6w8sSte7tHfN25DX9SegOH3uHDP1qSalFa+BPpca+bM76GG3dEXRsAjijc9VqsAtDEEbUfHhISFt/1TlgLmO7NoecWohiP873OU1qVhr0nXUZovBW3uz6w0Ci4dwmxU5+FgawdEXFZHmpaVQKIW/62K/YoswIHxRrucRkSEVIIQ==" Apr 20 14:00:09 volumio go-librespot[31429]: time="2025-04-20T14:00:09Z" 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]" Apr 20 14:00:09 volumio go-librespot[31429]: time="2025-04-20T14:00:09Z" level=debug msg="completed keyexchange" Apr 20 14:00:09 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-30103 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-28670 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-28669 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1222 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-787 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1219 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1220 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1221 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1223 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-13639 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-12986 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-888 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-145 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14482 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-139 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-13241 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1204 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-155 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14643 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14636 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-29659 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1803 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-18055 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1088 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-29404 Apr 20 14:00:09 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1208 Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-30103 in service upnp_browser Apr 20 14:00:09 volumio go-librespot[31429]: time="2025-04-20T14:00:09Z" level=debug msg="completed challenge" Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-28670 in service upnp_browser Apr 20 14:00:09 volumio go-librespot[31429]: time="2025-04-20T14:00:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-28669 in service upnp_browser Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1222 in service upnp_browser Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-787 in service upnp_browser Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1219 in service upnp_browser Apr 20 14:00:09 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1220 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1221 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1223 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-13639 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-12986 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-888 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-145 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14482 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-139 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-13241 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1204 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-155 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14643 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-14636 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-29659 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1803 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-18055 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1088 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-29404 in service upnp_browser Apr 20 14:00:10 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@4-1208 in service upnp_browser Apr 20 14:00:11 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:11 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1790. Apr 20 14:00:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:12 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:12 volumio go-librespot[31452]: Librespot-go daemon starting... Apr 20 14:00:12 volumio go-librespot[31452]: time="2025-04-20T14:00:12Z" level=info msg="generated new device id: f4e9d696c04b5b2289708e32f451b96ab89cda85" Apr 20 14:00:12 volumio go-librespot[31452]: time="2025-04-20T14:00:12Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:12 volumio go-librespot[31452]: time="2025-04-20T14:00:12Z" level=debug msg="obtained new client token: AACr54HWwY2hhAcPBf3Bhu1KF4LIIdCkRmqhd9JwAIP1eoQzoqplRe71YW8ol1TjheBqzBV9SdtUA/2ZWA//HwGBB5ERgbe+61oOQ37tB0rpEHwu4qeNkMNA53RFgGvht1hYiJSYbOI//f/OUKGJPITbGGlC3trTcqLN1CzNekPs0I+hLCVfQRUR8XmkZBb+/2qIpdcxboWoN05L6vPoA4wd7eGSC79ZfJKNy+wBHoV76sk4fVfVDwzYQ+5UlQ==" Apr 20 14:00:13 volumio go-librespot[31452]: time="2025-04-20T14:00:13Z" 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]" Apr 20 14:00:13 volumio go-librespot[31452]: time="2025-04-20T14:00:13Z" level=debug msg="completed keyexchange" Apr 20 14:00:13 volumio go-librespot[31452]: time="2025-04-20T14:00:13Z" level=debug msg="completed challenge" Apr 20 14:00:13 volumio go-librespot[31452]: time="2025-04-20T14:00:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:14 volumio sudo[31460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:00:14 volumio sudo[31460]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:14 volumio sudo[31460]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:14 volumio sudo[31462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:00:14 volumio sudo[31462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:14 volumio sudo[31462]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:14 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 20 14:00:14 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:14 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:14 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:14 volumio volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 14:00:14 volumio volumio[883]: info: Received Get System Version Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 14:00:14 volumio volumio[883]: info: Received Get System Info Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:00:14 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:14 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:00:14 volumio sudo[31469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:00:14 volumio sudo[31469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:14 volumio sudo[31469]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:15 volumio sudo[31471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:00:15 volumio sudo[31471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:15 volumio sudo[31471]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:15 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:15 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:00:15 volumio volumio[883]: info: Listing playlists Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:00:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1791. Apr 20 14:00:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:16 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:16 volumio go-librespot[31479]: Librespot-go daemon starting... Apr 20 14:00:16 volumio go-librespot[31479]: time="2025-04-20T14:00:16Z" level=info msg="generated new device id: b7c4c658952a3fdb568829132d56d3e43a861c7b" Apr 20 14:00:16 volumio go-librespot[31479]: time="2025-04-20T14:00:16Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:16 volumio go-librespot[31479]: time="2025-04-20T14:00:16Z" level=debug msg="obtained new client token: AADeNXHTe1Zw9qlw7GjvDzwAtOTOvLj2Lw68dI0LoCfJBiqoW4QfaGbJ6dpgXegktjLar/ty+M5xL++lOEOqEL1AAz5d0mRRvH8fN5BheGhwVDiPyzE/an4HKoNcGQoXZjcJYwNFol9BYkdNakEbEuVxxh91tbBhq5GRhLLyPBrwAagxwc6YaAtp9gUKsFVH++RU67o2FoeZS+qrFJ/Qk2cemBh47OF++sWckZeP7jKGNpJxUUHMNLMR4/2S+g==" Apr 20 14:00:17 volumio go-librespot[31479]: time="2025-04-20T14:00:17Z" 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]" Apr 20 14:00:17 volumio go-librespot[31479]: time="2025-04-20T14:00:17Z" level=debug msg="completed keyexchange" Apr 20 14:00:17 volumio go-librespot[31479]: time="2025-04-20T14:00:17Z" level=debug msg="completed challenge" Apr 20 14:00:17 volumio go-librespot[31479]: time="2025-04-20T14:00:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:17 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:17 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:18 volumio sudo[31486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:00:18 volumio sudo[31486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:18 volumio sudo[31486]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:18 volumio sudo[31488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:00:18 volumio sudo[31488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:18 volumio sudo[31488]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:18 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:18 volumio sudo[31496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:00:18 volumio sudo[31496]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:18 volumio sudo[31496]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:18 volumio sudo[31498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:00:18 volumio sudo[31498]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:00:18 volumio sudo[31498]: pam_unix(sudo:session): session closed for user root Apr 20 14:00:18 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:00:18 volumio volumio[883]: info: Listing playlists Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:00:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 20 14:00:19 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 20 14:00:19 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 20 14:00:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:00:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:00:20 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:00:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:00:20 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:20 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1792. Apr 20 14:00:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:20 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:20 volumio go-librespot[31518]: Librespot-go daemon starting... Apr 20 14:00:20 volumio go-librespot[31518]: time="2025-04-20T14:00:20Z" level=info msg="generated new device id: 3af4f4ef8614cb3471160d7ee99e2a013d194c21" Apr 20 14:00:20 volumio go-librespot[31518]: time="2025-04-20T14:00:20Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:21 volumio go-librespot[31518]: time="2025-04-20T14:00:21Z" level=debug msg="obtained new client token: AACFjyUAMax+6LtDNLcfBDTwXBZrKhQcekSshPtvLvVvwh5nNibLyHGIiHsLPXl4Zb4kmCAqhRNfXzKAkoVZhVVV+eqPl6QrOExNoSPI47TBAyT2JPY5xwjIVbdk3jAgFsNWUDXY8mcMPBT6kdMDUa65vHT2TBlvtZHJuVBT6oM9qnsP9ZsA8hWqAUw6f3IYNJ3Tud97j4UNabvtY260VyusavJbSTy2nnQDe1OPBcVBOpCXBet2JDYn70PQsQ==" Apr 20 14:00:21 volumio go-librespot[31518]: time="2025-04-20T14:00:21Z" 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]" Apr 20 14:00:21 volumio go-librespot[31518]: time="2025-04-20T14:00:21Z" level=debug msg="completed keyexchange" Apr 20 14:00:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 14:00:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 14:00:21 volumio go-librespot[31518]: time="2025-04-20T14:00:21Z" level=debug msg="completed challenge" Apr 20 14:00:21 volumio go-librespot[31518]: time="2025-04-20T14:00:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 14:00:22 volumio volumio[883]: info: CURURI: music-library Apr 20 14:00:22 volumio volumio[883]: error: Failed LSINFO: null Apr 20 14:00:22 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:23 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:23 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1793. Apr 20 14:00:24 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:24 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:24 volumio go-librespot[31526]: Librespot-go daemon starting... Apr 20 14:00:24 volumio go-librespot[31526]: time="2025-04-20T14:00:24Z" level=info msg="generated new device id: 54383460c435ae90c78a58c78b8ac0f950a28c69" Apr 20 14:00:24 volumio go-librespot[31526]: time="2025-04-20T14:00:24Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:24 volumio go-librespot[31526]: time="2025-04-20T14:00:24Z" level=debug msg="obtained new client token: AAAoG2rpOfZKhrwK3aUtFJS5o4cOuKusK4HffhdHNrQ+pIN873dUXjoP0g/edSHvpZdmWySDNFAbs27Rr8D9FPLxDZ4C88gNpH4P85ycwnMBbkIRSGKY5veVixEW7X45LigjwTS8joC6NbPxo2H27B+af4Cd3ehCwcZIDWKuMaupsf1BbaIpS8tewOOSrMrx/8H0ACuvoa/qHcjQQcsLAqGXhGYR8emeLpwvNz7bMA3f1lLfg8KSXykAhuFKIA==" Apr 20 14:00:25 volumio go-librespot[31526]: time="2025-04-20T14:00:25Z" 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]" Apr 20 14:00:25 volumio go-librespot[31526]: time="2025-04-20T14:00:25Z" level=debug msg="completed keyexchange" Apr 20 14:00:25 volumio go-librespot[31526]: time="2025-04-20T14:00:25Z" level=debug msg="completed challenge" Apr 20 14:00:25 volumio go-librespot[31526]: time="2025-04-20T14:00:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:26 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:26 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1794. Apr 20 14:00:28 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:28 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:28 volumio go-librespot[31533]: Librespot-go daemon starting... Apr 20 14:00:28 volumio go-librespot[31533]: time="2025-04-20T14:00:28Z" level=info msg="generated new device id: 5308ab7884e6f25a868ce80a9f02b2b39a0f40be" Apr 20 14:00:28 volumio go-librespot[31533]: time="2025-04-20T14:00:28Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:28 volumio go-librespot[31533]: time="2025-04-20T14:00:28Z" level=debug msg="obtained new client token: AAANxL0k3iwBORPuSfZLXXNbT65Zdxy5V4bbVmrY8DgENh9ValisOnO84RpxBO07zaaggSqwhrbuhR4OKPg2ko6L3eUoEke6uwPqPsskufQ02dwcO86wuPgblITS5ydTqeGIuGdR6VCNVbh1UOK6XRT26vdVRc0ow61OTcSmrIa4IMU8UzLpofqx9QwF8OusZs0I6y0ZyTgw20UVFskKlF9OAtaRQZue3W5Q08+b4XmPS5Pj+/4o2/xLAx/B6g==" Apr 20 14:00:29 volumio go-librespot[31533]: time="2025-04-20T14:00:29Z" 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]" Apr 20 14:00:29 volumio go-librespot[31533]: time="2025-04-20T14:00:29Z" level=debug msg="completed keyexchange" Apr 20 14:00:29 volumio go-librespot[31533]: time="2025-04-20T14:00:29Z" level=debug msg="completed challenge" Apr 20 14:00:29 volumio go-librespot[31533]: time="2025-04-20T14:00:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:29 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:29 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 14:00:29 volumio volumio[883]: info: CURURI: music-library Apr 20 14:00:29 volumio volumio[883]: error: Failed LSINFO: null Apr 20 14:00:29 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:32 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:32 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1795. Apr 20 14:00:32 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:32 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:32 volumio go-librespot[31554]: Librespot-go daemon starting... Apr 20 14:00:32 volumio go-librespot[31554]: time="2025-04-20T14:00:32Z" level=info msg="generated new device id: b3dc3aec0dbf6d834a408ffe6df595939d0c7792" Apr 20 14:00:32 volumio go-librespot[31554]: time="2025-04-20T14:00:32Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:33 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:33 volumio go-librespot[31554]: time="2025-04-20T14:00:33Z" level=debug msg="obtained new client token: AADQ9f2jkfIMVMUYa3Zp8jdzO337Nslz8AMAA7TW9ofKpsPBhp+YPIpB1xR22q5ZsacbzJZtkXofwEAj+FHpNFxC0QRYyf6NBckR9I9RBk4GyPwzUh0wM+vwkxru24kiLS3/mMC5t2rRzzE3+DWf6cq9jXbsc61gg0uVlon5YJMOSiEqjfxKolPbXcI/FsM5Igm1tlA1vqhjCy1XJneavQ/kEmtjdge8nC8rHq580ppn0jkVtoqomaa8i6tUGw==" Apr 20 14:00:33 volumio go-librespot[31554]: time="2025-04-20T14:00:33Z" 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]" Apr 20 14:00:33 volumio go-librespot[31554]: time="2025-04-20T14:00:33Z" level=debug msg="completed keyexchange" Apr 20 14:00:34 volumio go-librespot[31554]: time="2025-04-20T14:00:34Z" level=debug msg="completed challenge" Apr 20 14:00:34 volumio go-librespot[31554]: time="2025-04-20T14:00:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:34 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:35 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:35 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:00:36 volumio volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 14:00:36 volumio volumio[883]: info: Received Get System Version Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 14:00:36 volumio volumio[883]: info: Received Get System Info Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:00:36 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:00:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1796. Apr 20 14:00:37 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:37 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:37 volumio go-librespot[31564]: Librespot-go daemon starting... Apr 20 14:00:37 volumio go-librespot[31564]: time="2025-04-20T14:00:37Z" level=info msg="generated new device id: 800e99b7e3d79e554c81f90aa4d500a852b028a9" Apr 20 14:00:37 volumio go-librespot[31564]: time="2025-04-20T14:00:37Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:37 volumio go-librespot[31564]: time="2025-04-20T14:00:37Z" level=debug msg="obtained new client token: AABST2iW6OmtIi41TZog0o2cAn95M+4Qee6i0VwM7io137/OSj0iLTghWkaxFfWXGblklTsLuvCZOSPZmcpjR4aXKp/Nv1QxjZA4QdGzoD6sMvDBnYYGuiXcYgjgTZ/ddYKHPsQ+NzSWgvphtU1HD29l0c6d5GD3o9zEFN77jlwq7TaWCJyBrFnrCUPm8/Lz8sUWt/ZFJ9HkuHNXrGafyghwuSh8UclHomAvsshvGLsjvh2pqKW6HA/9Yq+HYw==" Apr 20 14:00:37 volumio go-librespot[31564]: time="2025-04-20T14:00:37Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:00:37 volumio go-librespot[31564]: time="2025-04-20T14:00:37Z" level=debug msg="completed keyexchange" Apr 20 14:00:38 volumio go-librespot[31564]: time="2025-04-20T14:00:38Z" level=debug msg="completed challenge" Apr 20 14:00:38 volumio go-librespot[31564]: time="2025-04-20T14:00:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:38 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:38 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:38 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:38 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:40 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:40 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1797. Apr 20 14:00:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:41 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:41 volumio go-librespot[31585]: Librespot-go daemon starting... Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" level=info msg="generated new device id: 88cddc55fe1ed3ce62549f4d1952ad4e330144c7" Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" level=debug msg="obtained new client token: AAApCL67wB3Y5Yk4HRyMoqdlOGbRe0qE9apdSQdhzNtX31q/QVl5ny6DZd+wrvwkO13gWonudgWpUxGRUQsDxZkMSzLeFjwzomV52VB3tfntvYV56ABZ+aN6YS4KbYVMyTdih8J3slNQjUXOnKqgPDZy9Q1GnDTEZV6xqSTx8gu5D0VNydGl3NpYUq40JJ2Gm4Fh6j6rdTOc9KpMedqm9xAbdd0LdUzMJoZHx5ht3obn/m7iRG2TPNieYONdtg==" Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" 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]" Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" level=debug msg="completed keyexchange" Apr 20 14:00:41 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:41 volumio go-librespot[31585]: time="2025-04-20T14:00:41Z" level=debug msg="new websocket client" Apr 20 14:00:41 volumio volumio[883]: info: Connection to go-librespot Websocket established Apr 20 14:00:42 volumio go-librespot[31585]: time="2025-04-20T14:00:42Z" level=debug msg="completed challenge" Apr 20 14:00:42 volumio go-librespot[31585]: time="2025-04-20T14:00:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:42 volumio volumio[883]: info: Connection to go-librespot Websocket closed Apr 20 14:00:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:42 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:43 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:44 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:44 volumio volumio[883]: info: Getting Spotify volume Apr 20 14:00:44 volumio volumio[883]: (node:883) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:44 volumio volumio[883]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 20 14:00:44 volumio volumio[883]: (node:883) 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: 948) Apr 20 14:00:44 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 20 14:00:44 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:44 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:44 volumio volumio[883]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 20 14:00:45 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:45 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1798. Apr 20 14:00:45 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:45 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:45 volumio go-librespot[31593]: Librespot-go daemon starting... Apr 20 14:00:45 volumio go-librespot[31593]: time="2025-04-20T14:00:45Z" level=info msg="generated new device id: 0a7036c933a40d9971dc07987a5feac796cf388a" Apr 20 14:00:45 volumio go-librespot[31593]: time="2025-04-20T14:00:45Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:45 volumio go-librespot[31593]: time="2025-04-20T14:00:45Z" level=debug msg="obtained new client token: AABCxJLtDoK6v3ocDTmW6CjFnbpetQV018B9NHDjIsznFfgYIrlG8MI8lgPY2E3b7fkkkCoWOZiPizrGzRzwDZdjJm6DE/p2dnG4fqKxEK0J7lK/OqYKy4U42q/KEGVFfYV4NtRtcOlpLnRCgR2c/hwtnHuNfGd1BL5KdorR5X7/n1W9VSk0Y5KnVEZ9jPqU5EwsADWlTne3wc8qKECTzANdaNLfRmjZfqpZ3fOi7z35zs8pAkjXccXU591c7w==" Apr 20 14:00:45 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:45 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-4 Apr 20 14:00:45 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-122 Apr 20 14:00:45 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-13882 Apr 20 14:00:45 volumio go-librespot[31593]: time="2025-04-20T14:00:45Z" 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]" Apr 20 14:00:45 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-4 in service upnp_browser Apr 20 14:00:45 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-122 in service upnp_browser Apr 20 14:00:45 volumio go-librespot[31593]: time="2025-04-20T14:00:45Z" level=debug msg="completed keyexchange" Apr 20 14:00:45 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305-13882 in service upnp_browser Apr 20 14:00:46 volumio go-librespot[31593]: time="2025-04-20T14:00:46Z" level=debug msg="completed challenge" Apr 20 14:00:46 volumio go-librespot[31593]: time="2025-04-20T14:00:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:47 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:48 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1222 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1219 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1220 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1221 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1223 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13639 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-12986 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14643 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14636 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1224 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1225 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-30121 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1718 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13667 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13494 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-15886 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1228 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13223 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1229 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14094 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1234 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1231 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1232 Apr 20 14:00:48 volumio volumio[883]: info: Preloading song: upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1233 Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1222 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:48 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1219 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1220 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1221 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1223 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13639 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-12986 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14643 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14636 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1224 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1225 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-30121 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1718 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13667 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13494 in service upnp_browser Apr 20 14:00:48 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-15886 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1228 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-13223 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1229 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-14094 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1234 in service upnp_browser Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1231 in service upnp_browser Apr 20 14:00:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1799. Apr 20 14:00:49 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1232 in service upnp_browser Apr 20 14:00:49 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:49 volumio go-librespot[31600]: Librespot-go daemon starting... Apr 20 14:00:49 volumio go-librespot[31600]: time="2025-04-20T14:00:49Z" level=info msg="generated new device id: e79f4a3341e8ee538dddefc07ed180c8a501aaf8" Apr 20 14:00:49 volumio go-librespot[31600]: time="2025-04-20T14:00:49Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:49 volumio volumio[883]: info: Exploding uri upnp/http://192.168.178.86:2869/upnphost/udhisapi.dll?control=uuid:b8091018-f768-4d4e-85ca-b6c899c5a883+urn:upnp-org:serviceId:ContentDirectory@14_4c634305_efbece0b-1233 in service upnp_browser Apr 20 14:00:49 volumio go-librespot[31600]: time="2025-04-20T14:00:49Z" level=debug msg="obtained new client token: AABTQHLi3kSn9KHe3f4L+kj8Ecge7dO24tCFauU++JJ9+HN3U9BXFDFVevPNaCT5aGEt/Bzh7FT9sSZL+T/pUDf/LCve3Rb3shpy9HsYnC9xt83ZU0G3dyxCEabpjzqZsaylukJoiQf+wpmaCP2sPU7+PcYwa0sOffQi3t531FLhWa4lo0JrNevjQOm9jnZOptS+D3uiW5I8M8EO5+Qzqi5nEG7xa8HV+NkfKzj5Dvk+8fVnFnJdbSLFsepelA==" Apr 20 14:00:49 volumio go-librespot[31600]: time="2025-04-20T14:00:49Z" 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]" Apr 20 14:00:49 volumio go-librespot[31600]: time="2025-04-20T14:00:49Z" level=debug msg="completed keyexchange" Apr 20 14:00:50 volumio go-librespot[31600]: time="2025-04-20T14:00:50Z" level=debug msg="completed challenge" Apr 20 14:00:50 volumio go-librespot[31600]: time="2025-04-20T14:00:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:50 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:00:50 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:00:50 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:00:50 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:00:50 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:00:50 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:00:51 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:51 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1800. Apr 20 14:00:53 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:53 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:53 volumio go-librespot[31674]: Librespot-go daemon starting... Apr 20 14:00:53 volumio go-librespot[31674]: time="2025-04-20T14:00:53Z" level=info msg="generated new device id: a9d829466045d7055480cbd6e991fad0c70a2829" Apr 20 14:00:53 volumio go-librespot[31674]: time="2025-04-20T14:00:53Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:53 volumio go-librespot[31674]: time="2025-04-20T14:00:53Z" level=debug msg="obtained new client token: AAC0kVBdMSzqHiStscv/WlEKSoZIztTSzjUT9C0qA7kVRPD4EtF77ECcpdvRTK5PK4eJJ6ZlXTgPEiCImD5/cUS20YtYN4ZTPUnTCng1oyvrOw7dvfm+y2qNBscw3bmSU3ZrkzsIV+p1q+KNcobU/0LxdViixI2DQiw/n52rEKk/XK6+7D8UKl0KUCi1c88qxXtlgqdnV8i/hqcasCgv2LXV+O4a37s0Zf88KVvBEwfTCYHD8TsAbNkpJj8tIw==" Apr 20 14:00:53 volumio go-librespot[31674]: time="2025-04-20T14:00:53Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:00:53 volumio go-librespot[31674]: time="2025-04-20T14:00:53Z" level=debug msg="completed keyexchange" Apr 20 14:00:54 volumio go-librespot[31674]: time="2025-04-20T14:00:54Z" level=debug msg="completed challenge" Apr 20 14:00:54 volumio go-librespot[31674]: time="2025-04-20T14:00:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:00:54 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:54 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:55 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:00:55 volumio volumio[883]: info: Preload queue cleared Apr 20 14:00:57 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:00:57 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:00:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:00:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1801. Apr 20 14:00:57 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:00:57 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:00:57 volumio go-librespot[31685]: Librespot-go daemon starting... Apr 20 14:00:57 volumio go-librespot[31685]: time="2025-04-20T14:00:57Z" level=info msg="generated new device id: f6294bdb2aeb13a47912a3f76579bc21d837c4de" Apr 20 14:00:57 volumio go-librespot[31685]: time="2025-04-20T14:00:57Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:00:57 volumio go-librespot[31685]: time="2025-04-20T14:00:57Z" level=debug msg="obtained new client token: AACdQUTac4peZEYz3rNVD+wXexzPp7ev3NGEIwjUfFJJudwELjloxbwn3V7R7YC6yK9UXotJYtYl/eKg469C1NlRpS+IVlrEQnOiRUmS1Ie7lt0bG1yUhzgJ1FzgR6hxIU0mPAIWMgIgO/QDQBKlKudLvSJBDewyiXB4WV1/8HxkaAvT29r2LCOlLWV22bP0rescRT0uFpfBm9dyAFy9fH7DtTGbJVf0EyQaQAxHb1JjbG2bJeiIjeoXhLtVlA==" Apr 20 14:00:57 volumio go-librespot[31685]: time="2025-04-20T14:00:57Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:00:57 volumio go-librespot[31685]: time="2025-04-20T14:00:57Z" level=debug msg="completed keyexchange" Apr 20 14:00:58 volumio go-librespot[31685]: time="2025-04-20T14:00:58Z" level=debug msg="completed challenge" Apr 20 14:00:58 volumio go-librespot[31685]: time="2025-04-20T14:00:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:00:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:00:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:00 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:00 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1802. Apr 20 14:01:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:01 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:01 volumio go-librespot[31706]: Librespot-go daemon starting... Apr 20 14:01:01 volumio go-librespot[31706]: time="2025-04-20T14:01:01Z" level=info msg="generated new device id: 90be02d1bf74454795ff6def590808afbfd25764" Apr 20 14:01:01 volumio go-librespot[31706]: time="2025-04-20T14:01:01Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:01 volumio go-librespot[31706]: time="2025-04-20T14:01:01Z" level=debug msg="obtained new client token: AAA6c1wxY43dfsSAxL/mujPfSVDNEyg0HSHp32wJoCG3Qtrs5ke5syzHa3PjVEDgxesH73C58o/TsgA3sXE9K6OVqXL5ANEdul66kHzWJT8p37M0npkkH6AXl3EWAL8borCqrwqTD96QgChWAbLlIsi5atUA9qwEeYW18JKNnH+U0jDC9FS6od4nf8HtKe0yOYGUMmTYxPIkL8oZnRjXybKftCuMAkT1OjVV7pAaWbIF9cZUh3C5Z00dwDLgtA==" Apr 20 14:01:01 volumio go-librespot[31706]: time="2025-04-20T14:01:01Z" 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]" Apr 20 14:01:01 volumio go-librespot[31706]: time="2025-04-20T14:01:01Z" level=debug msg="completed keyexchange" Apr 20 14:01:02 volumio go-librespot[31706]: time="2025-04-20T14:01:02Z" level=debug msg="completed challenge" Apr 20 14:01:02 volumio go-librespot[31706]: time="2025-04-20T14:01:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:03 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:03 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1803. Apr 20 14:01:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:05 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:05 volumio go-librespot[31713]: Librespot-go daemon starting... Apr 20 14:01:05 volumio go-librespot[31713]: time="2025-04-20T14:01:05Z" level=info msg="generated new device id: e6579cf337e9dea7ae6d8b48a2c06991cc4ae045" Apr 20 14:01:05 volumio go-librespot[31713]: time="2025-04-20T14:01:05Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:05 volumio go-librespot[31713]: time="2025-04-20T14:01:05Z" level=debug msg="obtained new client token: AACJusaRVG3WLeLqa6rK8s1EONIfDFkeMGYkD1AhfVKIysUIp/lqLFyWLlX6tmXqef3PLDm/KoiDJdQQKEwld69KdYXQnWpMI/dO6iUerg6aACMtH8SzJBDSZZ+9x55BxL9IQ077tDEGGVwdUuqfrE58ZdQsdgdbMylkGZwsyyUu0Ts77AGMAetfwGb72Gi8Aj10Z2rNNzxV3pO99VCIaQqwuFAbznlfHkdi0Y0hO5Rge1zvZV1hq2bPYI897Q==" Apr 20 14:01:05 volumio go-librespot[31713]: time="2025-04-20T14:01:05Z" 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]" Apr 20 14:01:05 volumio go-librespot[31713]: time="2025-04-20T14:01:05Z" level=debug msg="completed keyexchange" Apr 20 14:01:06 volumio go-librespot[31713]: time="2025-04-20T14:01:06Z" level=debug msg="completed challenge" Apr 20 14:01:06 volumio go-librespot[31713]: time="2025-04-20T14:01:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:06 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:06 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:09 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:09 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1804. Apr 20 14:01:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:09 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:09 volumio go-librespot[31720]: Librespot-go daemon starting... Apr 20 14:01:09 volumio go-librespot[31720]: time="2025-04-20T14:01:09Z" level=info msg="generated new device id: 89f634be881f6f20fa039584660114aa9f69ff08" Apr 20 14:01:09 volumio go-librespot[31720]: time="2025-04-20T14:01:09Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:09 volumio go-librespot[31720]: time="2025-04-20T14:01:09Z" level=debug msg="obtained new client token: AAAmh/GmV3jtg/7E3y2CpycgzZ+c/8TVIfOgQfJujJKAfKiTqDp0jIqDM+oY5sHq+ygNhiXfkXmiAmw8P2BkN3KCV5tk5Ni+GSWyQtWn8NI6s8uTP8MO0iBSsFDRXXO+2q6K+d6/K9v3eZeYSTUy5KaMLoFHe4gmmFwswb7lrcbOcLmVZEs74PA86WL1eLbcSbSi5m7bAaCpkAZ80xeGo8mVLm63G5f+QEXKNubOnN1m7WrIcdGi4HtbMilK/A==" Apr 20 14:01:09 volumio go-librespot[31720]: time="2025-04-20T14:01:09Z" 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]" Apr 20 14:01:09 volumio go-librespot[31720]: time="2025-04-20T14:01:09Z" level=debug msg="completed keyexchange" Apr 20 14:01:10 volumio go-librespot[31720]: time="2025-04-20T14:01:10Z" level=debug msg="completed challenge" Apr 20 14:01:10 volumio go-librespot[31720]: time="2025-04-20T14:01:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:12 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:12 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:12 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:12 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1805. Apr 20 14:01:13 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:13 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:13 volumio go-librespot[31743]: Librespot-go daemon starting... Apr 20 14:01:13 volumio go-librespot[31743]: time="2025-04-20T14:01:13Z" level=info msg="generated new device id: 4e7380fd58d989be929c0b50999c0fd291c1ae30" Apr 20 14:01:13 volumio go-librespot[31743]: time="2025-04-20T14:01:13Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:13 volumio go-librespot[31743]: time="2025-04-20T14:01:13Z" level=debug msg="obtained new client token: AACColBwTvqASQt4XsTrtjvKFS0yTUbZqn4xzd2p+0Qcmh8CPA7GWtfPSK7rKigKwGuhi11KrJntckujpFWTu9aNsdbwuhQcSUQAZSRXrTVFTW8Ej0iyrIf2rGGO6mywZW8rfWHrefVATSz+TGaXiwt2CIy8OiA7E4OeSqr/wtSFob1/aJak0KnLYeN7WaIEie77OiKhT6TuXibeJcg+6Yky7vMl/ScXGZOGlg6+R708KRpqHHNfyw2wvyeyAQ==" Apr 20 14:01:13 volumio go-librespot[31743]: time="2025-04-20T14:01:13Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:01:13 volumio go-librespot[31743]: time="2025-04-20T14:01:13Z" level=debug msg="completed keyexchange" Apr 20 14:01:14 volumio go-librespot[31743]: time="2025-04-20T14:01:14Z" level=debug msg="completed challenge" Apr 20 14:01:14 volumio go-librespot[31743]: time="2025-04-20T14:01:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:15 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:15 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:15 volumio sudo[31754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:01:15 volumio sudo[31754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:01:15 volumio sudo[31754]: pam_unix(sudo:session): session closed for user root Apr 20 14:01:15 volumio sudo[31756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:01:15 volumio sudo[31756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:01:15 volumio sudo[31756]: pam_unix(sudo:session): session closed for user root Apr 20 14:01:15 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:01:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:01:16 volumio sudo[31762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 14:01:16 volumio sudo[31762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:01:16 volumio sudo[31762]: pam_unix(sudo:session): session closed for user root Apr 20 14:01:16 volumio sudo[31765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 14:01:16 volumio sudo[31765]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 14:01:16 volumio sudo[31765]: pam_unix(sudo:session): session closed for user root Apr 20 14:01:16 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.65 from 192.168.178.142 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:01:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 14:01:16 volumio volumio[883]: info: Listing playlists Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 14:01:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:01:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1806. Apr 20 14:01:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:17 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:17 volumio go-librespot[31769]: Librespot-go daemon starting... Apr 20 14:01:17 volumio go-librespot[31769]: time="2025-04-20T14:01:17Z" level=info msg="generated new device id: 6f73fd095c16e5301b415a8751b101199af6ea43" Apr 20 14:01:17 volumio go-librespot[31769]: time="2025-04-20T14:01:17Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 20 14:01:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 20 14:01:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 20 14:01:17 volumio go-librespot[31769]: time="2025-04-20T14:01:17Z" level=debug msg="obtained new client token: AAATifBMxxjo1m/XQxEOKymNxGRmqCyO3E53gRHDXG8Ds4E8iZqKUGIO6cyxwClF9pXrUU76LBQMeajwL8HMsx0AY1kruZIcw6b3A1A1qy/HqqplADw7aFz4LzwEE3dYnn1TtpEJcO3hNYGUrE0VH2fj1fi0pO7g2SxDbxggVXQNUChac7JbQac3htoOf/8hzi0YdLUUNudDZyrAJXFDPe7fg8zaUOHUvcmbY00sC59H818VwujZxYhyExlcHw==" Apr 20 14:01:17 volumio go-librespot[31769]: time="2025-04-20T14:01:17Z" 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]" Apr 20 14:01:17 volumio go-librespot[31769]: time="2025-04-20T14:01:17Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Apr 20 14:01:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 14:01:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 14:01:18 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:18 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 14:01:20 volumio volumio[883]: info: CURURI: albums:// Apr 20 14:01:20 volumio volumio[883]: info: listAlbums - loading Albums from cache Apr 20 14:01:20 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:01:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:01:20 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:01:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:01:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:01:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:01:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1807. Apr 20 14:01:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:20 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:20 volumio go-librespot[31790]: Librespot-go daemon starting... Apr 20 14:01:20 volumio go-librespot[31790]: time="2025-04-20T14:01:20Z" level=info msg="generated new device id: 871d10624b5f2a8831d073df2705866f6c5afcbb" Apr 20 14:01:20 volumio go-librespot[31790]: time="2025-04-20T14:01:20Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:20 volumio go-librespot[31790]: time="2025-04-20T14:01:20Z" level=debug msg="obtained new client token: AACd7Nry+vzfeeuzKeeGKUUE+QIQSy7/Re7plN8WRf+aPZg6pqSBkiGcKyI6sTIj4QSbLhFgq/4EBcogEzaXZWA6KpAwNAMzl+dbhrjDZY5AGxdDBANEJ+ZdXGOompcWyozl2YIyt6hUrKOmT24BbSkrhR6lLeIje+LSkg/bs02ul1S0Ffmj2Y+YVsofI40jO+HkZaPQtw0WNFNeDGN/Hzj75+0OREmjPNL3sh/akPgYJqAZfwJoCSte6IaEUA==" Apr 20 14:01:21 volumio go-librespot[31790]: time="2025-04-20T14:01:21Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:01:21 volumio go-librespot[31790]: time="2025-04-20T14:01:21Z" level=debug msg="completed keyexchange" Apr 20 14:01:21 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:21 volumio go-librespot[31790]: time="2025-04-20T14:01:21Z" level=debug msg="new websocket client" Apr 20 14:01:21 volumio volumio[883]: info: Connection to go-librespot Websocket established Apr 20 14:01:21 volumio go-librespot[31790]: time="2025-04-20T14:01:21Z" level=debug msg="completed challenge" Apr 20 14:01:21 volumio go-librespot[31790]: time="2025-04-20T14:01:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:21 volumio volumio[883]: info: Connection to go-librespot Websocket closed Apr 20 14:01:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 20 14:01:23 volumio volumio[883]: info: CURURI: music-library Apr 20 14:01:23 volumio volumio[883]: error: Failed LSINFO: null Apr 20 14:01:23 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:24 volumio volumio[883]: info: Getting Spotify volume Apr 20 14:01:24 volumio volumio[883]: (node:883) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:24 volumio volumio[883]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 20 14:01:24 volumio volumio[883]: (node:883) 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: 949) Apr 20 14:01:24 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Apr 20 14:01:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:01:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:01:24 volumio volumio[883]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 20 14:01:24 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:24 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1808. Apr 20 14:01:24 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:24 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:24 volumio go-librespot[31796]: Librespot-go daemon starting... Apr 20 14:01:24 volumio go-librespot[31796]: time="2025-04-20T14:01:24Z" level=info msg="generated new device id: 9321957b5ae193cba7d3822b0f6e809c426802cd" Apr 20 14:01:24 volumio go-librespot[31796]: time="2025-04-20T14:01:24Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:24 volumio go-librespot[31796]: time="2025-04-20T14:01:24Z" level=debug msg="obtained new client token: AACCn+PZx+YZg5gCdlEg0Qkcxokvyhzf2POXbIPCpy4UXv1qVQU66FUFvA7vquMP27fNGrJg+h5/T+qb6w2J504nddi0pOrIyj5xIVRy06kcEjp3RuQf2gVNfL6QGTIAt3QU/qZ093q1hGSVoFo5I3EbwSstdjQUAoTFNp62ZVAKd5iySDslAYYb/AjODnW8ttgiGAyVLGuVjjM3olqI+6XCJ1ucWxEAfDOT3u3gjlNxdK8+1X3fjF0cI3YOmg==" Apr 20 14:01:25 volumio go-librespot[31796]: time="2025-04-20T14:01:25Z" 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]" Apr 20 14:01:25 volumio go-librespot[31796]: time="2025-04-20T14:01:25Z" level=debug msg="completed keyexchange" Apr 20 14:01:25 volumio go-librespot[31796]: time="2025-04-20T14:01:25Z" level=debug msg="completed challenge" Apr 20 14:01:25 volumio go-librespot[31796]: time="2025-04-20T14:01:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:26 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:26 volumio volumio[883]: info: Preloading song: spotify:track:0GRvnYqlnh7ttq9NS6s3Ta Apr 20 14:01:27 volumio volumio[883]: info: Exploding uri spotify:track:0GRvnYqlnh7ttq9NS6s3Ta in service spop Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: EXPLODING URI:spotify:track:0GRvnYqlnh7ttq9NS6s3Ta Apr 20 14:01:27 volumio volumio[883]: info: Exploding uri spotify:track:0GRvnYqlnh7ttq9NS6s3Ta in service spop Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: EXPLODING URI:spotify:track:0GRvnYqlnh7ttq9NS6s3Ta Apr 20 14:01:27 volumio volumio[883]: info: Exploding uri spotify:track:0GRvnYqlnh7ttq9NS6s3Ta in service spop Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: EXPLODING URI:spotify:track:0GRvnYqlnh7ttq9NS6s3Ta Apr 20 14:01:27 volumio volumio[883]: info: New access token = BQC7ylGW28HMHFHpsh4ToGNxKj_mo5TXYghykmofQp6WadjW5mS07CgXx1EYzlfNK89_CvNXBd_k1oCpCvO8IhaXODS_t3cZDvFYTXRCBsvBPskev_u9GZ4EcSTLOtWhCkOWMsMMepmG4LNZyf_TJ7XExWbmEnCcYx07YguTCpPRWP_xA0ILVA147vNWUe7SHzZbA2LYeOjf1DpZv2_n11C91YYpZtmn8lEucdwU0nHR2Qr97jyi6rz8uJmhPCkB72ubgSz2WrGMM3rFD01ZMRPgWIudLllJWjqfu61nb2c Apr 20 14:01:27 volumio volumio[883]: info: New access token = BQBgMfR-y0QkOLaU4PkiFmqHpJuzCNTWEL4CUPAFHiyh7kUGyelnG8MBL8hzSRsXXQlFlQqQB2V4ZIdRoNZokJQ-GiUTCxNngDZLk2P0SmWaBvVuhIC3dyXmcQ94_rtAjqNWqEVtGiIKfAmQhGtzswlTIPUkAAkH7CBg7nuP3bb9bsofqhC82ZfS17lGVShgLOxFhYYXSF5V30rVOLJdz-fAKjeF10T3x6We90PTxzcLlzlQskU7mBSMoIb31pkvjt1B6sc-ZAOEdPASEWqxWdTRHf7wPEGz1g98vVjX9jQ Apr 20 14:01:27 volumio volumio[883]: info: New access token = BQDEyAhXtgrLn8v_9ELsBwzdeL2BelUxyf4k6TaQi2BGkkMs8E5Ou1Z7dpdq1XC0AMeJb51S2lv2sa8qUMRMIsn275TzHaicu9zbOIgb3dbcKdYg6N3oUypXCfh0YIkqEvLSe1INHQEECG68TKZ8MtDvVhvZcqA0db5RCHZAkOxz-m7XTFDa3J_v5KXR6RcL-YcK7aYGh8vDbKp7ntiAfihGN0ycWwVYAVbJTFK4Nkr_csbt4y60JeMGDHXJCPRdF538o0jgMZUC3aXPVKwkkLk91HtlNIMaGrqbHar7oh8 Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRvnYqlnh7ttq9NS6s3Ta","service":"spop","name":"Desecration Smile","artist":"Red Hot Chili Peppers","album":"Stadium Arcadium","type":"song","duration":301,"albumart":"https://i.scdn.co/image/ab67616d0000b27309fd83d32aee93dceba78517","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRvnYqlnh7ttq9NS6s3Ta","service":"spop","name":"Desecration Smile","artist":"Red Hot Chili Peppers","album":"Stadium Arcadium","type":"song","duration":301,"albumart":"https://i.scdn.co/image/ab67616d0000b27309fd83d32aee93dceba78517","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 20 14:01:27 volumio volumio[883]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRvnYqlnh7ttq9NS6s3Ta","service":"spop","name":"Desecration Smile","artist":"Red Hot Chili Peppers","album":"Stadium Arcadium","type":"song","duration":301,"albumart":"https://i.scdn.co/image/ab67616d0000b27309fd83d32aee93dceba78517","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 20 14:01:27 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:27 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1809. Apr 20 14:01:28 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:28 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:28 volumio go-librespot[31803]: Librespot-go daemon starting... Apr 20 14:01:28 volumio go-librespot[31803]: time="2025-04-20T14:01:28Z" level=info msg="generated new device id: a81d8d4899e74246916d1f9e069399de3a92c54f" Apr 20 14:01:28 volumio go-librespot[31803]: time="2025-04-20T14:01:28Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:28 volumio go-librespot[31803]: time="2025-04-20T14:01:28Z" level=debug msg="obtained new client token: AADaLQT9fiYpf0rA5j7PFUOXWzKGbd4TqHCHZBDbBGAJJaI0PhhzEZm89vgqs9TjceLvEetpHToi43g7qDrmuGJoLtJM5ftUlevBepfyUlvtXVszahcdzFHOrdz2u0rAteQa56YsuEf7frJGg8leQfVhoSUliqAO7ELE1LfIunNZL1SJ6eKdkic1T+zra48rQsf+ZmBox+7KLIJ0byUGNbDB4r6K9uUu6AvvOO/9mkw/RLcuuF14kcuSHh3hjg==" Apr 20 14:01:29 volumio go-librespot[31803]: time="2025-04-20T14:01:29Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:01:29 volumio go-librespot[31803]: time="2025-04-20T14:01:29Z" level=debug msg="completed keyexchange" Apr 20 14:01:29 volumio go-librespot[31803]: time="2025-04-20T14:01:29Z" level=debug msg="completed challenge" Apr 20 14:01:29 volumio go-librespot[31803]: time="2025-04-20T14:01:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:30 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:30 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1810. Apr 20 14:01:32 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:32 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:32 volumio go-librespot[31824]: Librespot-go daemon starting... Apr 20 14:01:32 volumio go-librespot[31824]: time="2025-04-20T14:01:32Z" level=info msg="generated new device id: f948bbe1c75277d28096400fa7cd3035e84e7042" Apr 20 14:01:32 volumio go-librespot[31824]: time="2025-04-20T14:01:32Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:32 volumio go-librespot[31824]: time="2025-04-20T14:01:32Z" level=debug msg="obtained new client token: AACXe2+a2FJVMHS+f5578ilkc9tGH1GLGsXcwUCKl8MdawhjHQKNgtjkJ6pF49CESI+AvvKKLB5cCc+F+fP5T1SHYToiR7CxvPo1l7bWj/Xj8ZG1EmILkrDbzM5ODwaH87TsnxLNoLUB+l3s7Znlq9pFO3DvVEXtikhk/iNXdygJpImMIA8M1e6ZkKOzXD9poKR5J1IFF1tR8uLUvvmKOf9u5jNxCv76oopidWnGsuT/nwdP7JfEURybR0Q31w==" Apr 20 14:01:33 volumio go-librespot[31824]: time="2025-04-20T14:01:33Z" 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]" Apr 20 14:01:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:33 volumio go-librespot[31824]: time="2025-04-20T14:01:33Z" level=debug msg="completed keyexchange" Apr 20 14:01:33 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:33 volumio go-librespot[31824]: time="2025-04-20T14:01:33Z" level=debug msg="completed challenge" Apr 20 14:01:33 volumio go-librespot[31824]: time="2025-04-20T14:01:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:33 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:33 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:34 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 14:01:34 volumio volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 14:01:34 volumio volumio[883]: info: Received Get System Version Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 14:01:34 volumio volumio[883]: info: Received Get System Info Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 14:01:34 volumio volumio[883]: info: Discovery: Getting this device information Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 20 14:01:34 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 20 14:01:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 14:01:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:36 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:36 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:36 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1811. Apr 20 14:01:36 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:36 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:36 volumio go-librespot[31834]: Librespot-go daemon starting... Apr 20 14:01:36 volumio go-librespot[31834]: time="2025-04-20T14:01:36Z" level=info msg="generated new device id: fda66b36a7444339ced4b8d31c77adc33ca6f76f" Apr 20 14:01:36 volumio go-librespot[31834]: time="2025-04-20T14:01:36Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:37 volumio go-librespot[31834]: time="2025-04-20T14:01:37Z" level=debug msg="obtained new client token: AAAFsboyWlMrDvNVN/tMtnRPr5IK74Q3L7P91h73B43janCVOAy56EVVMzp4OvaMndC9kcRRFz0XgRIIoqmbjo8BLoksV+U1nqqvoPtYx3/NINEqn3mxN7qfdCyxyVuVMZR7Rvm232fYbRa4ryozFZ7OgPpI+Y2dc4thj7eynKqB0NQxKIpwCQsPBDFVDTJ1r7tBtdkmYZHJf0oOJqoj/ll4711iSjuG4acmwi5WlyHsp4pH1eADq7fFxyfdfQ==" Apr 20 14:01:37 volumio go-librespot[31834]: time="2025-04-20T14:01:37Z" 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]" Apr 20 14:01:37 volumio go-librespot[31834]: time="2025-04-20T14:01:37Z" level=debug msg="completed keyexchange" Apr 20 14:01:37 volumio go-librespot[31834]: time="2025-04-20T14:01:37Z" level=debug msg="completed challenge" Apr 20 14:01:37 volumio go-librespot[31834]: time="2025-04-20T14:01:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:39 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:39 volumio volumio[883]: info: Preload queue cleared Apr 20 14:01:39 volumio volumio[883]: info: Initializing connection to go-librespot Websocket Apr 20 14:01:39 volumio volumio[883]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 20 14:01:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 20 14:01:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1812. Apr 20 14:01:40 volumio systemd[1]: Stopped go-librespot Daemon. Apr 20 14:01:40 volumio systemd[1]: Started go-librespot Daemon. Apr 20 14:01:40 volumio go-librespot[31856]: Librespot-go daemon starting... Apr 20 14:01:40 volumio go-librespot[31856]: time="2025-04-20T14:01:40Z" level=info msg="generated new device id: 57ce88d6258a15879cd755f88fa697fa84035049" Apr 20 14:01:40 volumio go-librespot[31856]: time="2025-04-20T14:01:40Z" level=debug msg="stored credentials found for m3t8tb0v7thenxpvb101m5iur" Apr 20 14:01:40 volumio go-librespot[31856]: time="2025-04-20T14:01:40Z" level=debug msg="obtained new client token: AACyyRgbQIJkJMS3jpZHYjOkhNnAU3lmEDYh71XQDeE98Y34fVa3zwMkquEfWdYKSXTwPTvBOShrSLIGa3M4Sb+pgzKodQIG8TWRdHAb6zmnOiKMNZJzV/KT3UG7DkVMHJVsPUgdodUrNEJ2b+jFlCDollHFVXTgzPgJnSmGVojJYu95XPWLiFiQH9iO3wG+pLUXtVa6BFkpXvd/0zpwDecSqFt5CTtmuehNKH+34ssjj6DgCu3qrpBcPJxq4g==" Apr 20 14:01:41 volumio go-librespot[31856]: time="2025-04-20T14:01:41Z" 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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 14:01:41 volumio go-librespot[31856]: time="2025-04-20T14:01:41Z" level=debug msg="completed keyexchange" Apr 20 14:01:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 20 14:01:41 volumio go-librespot[31856]: time="2025-04-20T14:01:41Z" level=debug msg="completed challenge" Apr 20 14:01:41 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 14:01:41 volumio volumio[883]: TypeError: Cannot read property 'length' of undefined Apr 20 14:01:41 volumio volumio[883]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 20 14:01:41 volumio volumio[883]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 20 14:01:41 volumio volumio[883]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 20 14:01:41 volumio volumio[883]: at Parser.emit (events.js:400:28) Apr 20 14:01:41 volumio volumio[883]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 20 14:01:41 volumio volumio[883]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 20 14:01:41 volumio volumio[883]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 20 14:01:41 volumio volumio[883]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 20 14:01:41 volumio volumio[883]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 20 14:01:41 volumio volumio[883]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 20 14:01:41 volumio volumio[883]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 20 14:01:41 volumio volumio[883]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 20 14:01:41 volumio volumio[883]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 20 14:01:41 volumio volumio[883]: at IncomingMessage.emit (events.js:412:35) Apr 20 14:01:41 volumio volumio[883]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 20 14:01:41 volumio volumio[883]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 20 14:01:41 volumio volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 14:01:41 volumio go-librespot[31856]: time="2025-04-20T14:01:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 20 14:01:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 20 14:01:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 20 14:01:41 volumio sudo[31872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 14:00 Apr 20 14:01:41 volumio sudo[31872]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"