-- Logs begin at Sun 2025-01-19 13:29:00 CET, end at Sun 2025-01-19 15:29:57 CET. -- Jan 19 15:28:00 music-player go-librespot[7920]: time="2025-01-19T15:28:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:00 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:00 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:00 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:00 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:03 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:03 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1379. Jan 19 15:28:03 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:03 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:03 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:03 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:03 music-player go-librespot[7936]: Librespot-go daemon starting... Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=info msg="generated new device id: e9850bd19f9997441b35f2233be83bc5f35500c7" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="zeroconf server listening on port 34687" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="obtained new client token: AABvHY5F4Ex1XxETDMXEKMrAmWYPEyEbilL63Zmk0rnj+j0qJ6Z3TI87dd71lrm7HxDVhcNsGoE8sDIH6p2a2lZyIK0eSr4gfVTyz8llP8cszTRrgxhfQvJ70w1xEeDKZCTdtJSVoPcy63S6iqy6mKIY22txyBu+vf22iyJyKyqDM0we9Z8i+uOITXW59OV9FL12idL+8Tj5CpwExD5oMowas56Kg10xPk+67cCdhrWHsQYbpEfE0u+0O2maqak=" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=debug msg="completed challenge" Jan 19 15:28:03 music-player go-librespot[7936]: time="2025-01-19T15:28:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:03 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:04 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:06 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:06 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:07 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:07 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1380. Jan 19 15:28:07 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:07 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:07 music-player go-librespot[7952]: Librespot-go daemon starting... Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=info msg="generated new device id: 8e332ff24a2e38939c659964b58eefb77f9a2bf3" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="zeroconf server listening on port 40827" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="obtained new client token: AADpr0CedhEzjjtpIuJjQT4EHAFNWRHgt0sQ0kVmNRmf4lnzCwBG762XMf7jM/fk+sScYs+u3ASGiBb9p7dlP7e1Wj2o05KLywM2vgxkrlHwcINO0C4uqw2vLAguvprns2gd9SmDyy+XxyOqAFTZKKyx/aDNoEfUmyDgA3UJxgyzewRNSi2RRMuO5nYl62RhauSQpmu5jYxn8nAyDN227dTYfwvNSG/UE2tBZTRsD+pTi9yLX4mg2eMuhBVepi0=" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:07 music-player go-librespot[7952]: time="2025-01-19T15:28:07+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:08 music-player go-librespot[7952]: time="2025-01-19T15:28:08+01:00" level=debug msg="completed challenge" Jan 19 15:28:08 music-player go-librespot[7952]: time="2025-01-19T15:28:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:08 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:08 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:09 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:09 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:11 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:11 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1381. Jan 19 15:28:11 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:11 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:11 music-player go-librespot[7985]: Librespot-go daemon starting... Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=info msg="generated new device id: 7b85f9323584beb170a2a4672085218609f22375" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="zeroconf server listening on port 36559" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="obtained new client token: AAB5JZd3Cu20YkKTIhbqcT2Vii5NrH/sDfB3wa6KbSLtOGhNBygZ2go3tSnBqeWOrbrlVstKYuS4mvCDSMGC9M0uGHs6/P/x3P9YUw3zMWYIXc23M+MXejT49c8T86h4BZ1w/6mZdHYf87PfCFsMIOSgsh9Z9RkziaDxztm6xnPD5tVQywpkyBfDrdrvhxBMEmC9nCu15UVpOM2NayXJFdtDf1w1B0paIymYQPlHw+r3XQOTI13EBeo9nwILFjM=" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:11 music-player go-librespot[7985]: time="2025-01-19T15:28:11+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:12 music-player go-librespot[7985]: time="2025-01-19T15:28:12+01:00" level=debug msg="completed challenge" Jan 19 15:28:12 music-player go-librespot[7985]: time="2025-01-19T15:28:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:12 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:12 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:12 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:12 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:15 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:15 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1382. Jan 19 15:28:15 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:15 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:15 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:15 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:15 music-player go-librespot[8001]: Librespot-go daemon starting... Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=info msg="generated new device id: 343b35553acf6a4051f4922ab8b93a96dffe5bf2" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="zeroconf server listening on port 38719" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="obtained new client token: AAAUs+UKdb2l8v8q4Y9mf5lMxzaZU+nmtVopUmXjM7eaxmgrNDTx1iqeBa6LqWvVBcDG0WF9c84KrpeSdbEn/A9E+CCT6rz6a2CwmVCIX31VXyPoBg8Yyb5PD/CzH3P+u274tZWZNMrQcQpAzm93rqMFaL216fVdtiFiAnf4vDaC+Gm/axCEpy7xiY8TvcZIfuUGTL2Q9zFcfRle9sqs+slyOQMClRNnjBAiJgdGyL9HJEXgtH198o2asI7NVm4=" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:15 music-player go-librespot[8001]: time="2025-01-19T15:28:15+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:15 music-player volumiologrotate[768]: ls: cannot access '/var/log/samba/log.wb-MUSIC': No such file or directory Jan 19 15:28:15 music-player volumiologrotate[768]: ls: cannot access 'PLAYER': No such file or directory Jan 19 15:28:16 music-player go-librespot[8001]: time="2025-01-19T15:28:16+01:00" level=debug msg="completed challenge" Jan 19 15:28:16 music-player go-librespot[8001]: time="2025-01-19T15:28:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:16 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:16 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:18 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:18 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:19 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:19 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1383. Jan 19 15:28:19 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:19 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:19 music-player go-librespot[8079]: Librespot-go daemon starting... Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=info msg="generated new device id: f696be7bd624434a0784389e6cf5c71ea9fb3c3a" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="zeroconf server listening on port 37605" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="obtained new client token: AAAgG+dnb3OvLLwuV+hiXOTxI2+jQ1FJYRi2A3IA2BNJv9RjRwflXxqGjYI+eIm08lwedEK/OyOxASZaW4Hq8yQ9Mk9RG0aryXMawzaPgswYWP6obWv60CV0v5hNOjkUmIq0hFFWLDLvG/WE1atuiJOwhRGsR3rOnMG2+V3qIOhyVeey3rEmPd0W1vjfKfy/FkjGGmHk6gUqGV+L9glARTT0LEIBDF6u6w3w3RE9MEt/QoXWPkqibeRt06rFg/A=" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:19 music-player go-librespot[8079]: time="2025-01-19T15:28:19+01:00" level=debug msg="completed challenge" Jan 19 15:28:20 music-player go-librespot[8079]: time="2025-01-19T15:28:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:20 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:20 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:21 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:21 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:23 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:23 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1384. Jan 19 15:28:23 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:23 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:23 music-player go-librespot[8095]: Librespot-go daemon starting... Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=info msg="generated new device id: 0bd1de2f4fe3d4482b8efcab45a0b4061fbb4df6" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="zeroconf server listening on port 40725" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="obtained new client token: AADDMxBVRRD/aj6BIcMvtxqgRAfghnP/OrdnA4dHIevm4BXCaUxejbziQeGjFY4Wg+pk+U6TW+irU/DJifx2sS7/xeohXWz1XK4OPhNw8ni3Q+i2TY4oLUl5bpouE0GocaR2Xt0LYVPZQFTZaofM6T1MG0MaWN7MibQQuLT/XdOMt1p/sI8/5EixIUo9+h+1t/pP4892FXlEcK72fN0v/uYAlu9/I/vFkDSVbhVWsLzLBGkfGQJ+6tRyumFZ6UY=" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:23 music-player go-librespot[8095]: time="2025-01-19T15:28:23+01:00" level=debug msg="completed challenge" Jan 19 15:28:24 music-player go-librespot[8095]: time="2025-01-19T15:28:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:24 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:24 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:24 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:24 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:27 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:27 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1385. Jan 19 15:28:27 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:27 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:27 music-player go-librespot[8111]: Librespot-go daemon starting... Jan 19 15:28:27 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:27 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=info msg="generated new device id: a7d9a319e45a77f1a8acbb663d6df98af999f03d" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="zeroconf server listening on port 45205" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="obtained new client token: AABm274Av1T9r1b7+LtjeHCkIqlodmbxtHL53RtiZBUjWxQBgTMcpeHuSHtH3SkhzD8dCHh5ndmT8QxziTi4qByRTZOKeEC+xQ8uCn7C4ORHsr74sQGRT69FJ8/dclZE8QwdD0odOCVfNyGQTp9YRPFTLR5ubHY7G+UCKq6w/I/mTPlCIiR7AcfAK+IsT5obycTh7XLjgMBEaWXxV263V8CjPNCUFzGUsj48Yag0VAJIf4rIgXRkTid7PMtjFZw=" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=debug msg="completed challenge" Jan 19 15:28:27 music-player go-librespot[8111]: time="2025-01-19T15:28:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:28 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:28 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:30 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:30 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:31 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:31 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1386. Jan 19 15:28:31 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:31 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:31 music-player go-librespot[8144]: Librespot-go daemon starting... Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=info msg="generated new device id: 47d3b32b9944ec8efc65b097eb55aca8710f4804" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="zeroconf server listening on port 37381" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="obtained new client token: AACSW4sVShgnb/zRfWoADdEQlw9qtm0nCUaCeVsDf4tkh/azHaZV0QxyGhQvsDPhKeDLRzOMI0Lmngw4TGmpRcZwmWhDZR386i9N6uIA0P6gCjR9s3dljJZCe2bxXld95o+SGZ0xJ1TPXwPjvzmAcQhG+lASniEqM64zFpPJcUS3vqbcBCFbpb+eP9AjJyotnMM+zY8dhugQNRSn9+E0yCBw4ulyYCnSBvRaKoW861c767Nu4/Vz5ypsRDzkDlg=" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:31 music-player go-librespot[8144]: time="2025-01-19T15:28:31+01:00" level=debug msg="completed challenge" Jan 19 15:28:32 music-player go-librespot[8144]: time="2025-01-19T15:28:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:32 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:32 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:33 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:33 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:35 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:35 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1387. Jan 19 15:28:35 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:35 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:35 music-player go-librespot[8160]: Librespot-go daemon starting... Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=info msg="generated new device id: 95c57f043711a80fadb42babc098b591dc5c0daa" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="zeroconf server listening on port 45837" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="obtained new client token: AAAcwrFakuVvAFQsDxP0Csir9qh8/p8j/8wV8B3cXm+rbBmxiaEi6IrbYpbj6550Oevvxz7gLqjJ9lXLehHUicRFKvTI1M2o4zgvtEX0tGIfxh0LDqRKUaE1gjEDBr06tCO0ao1c94l/nIMlQt+N75dWEZ+b8oibSg/V3F4NnnJ+/DyWPhew87vxmlsPUYmhm4/n297922ab0SSPixWILegQm5TGbBP5C0VM/jtMqDF6H8qGG1wKVbcqNaYTZGY=" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:35 music-player go-librespot[8160]: time="2025-01-19T15:28:35+01:00" level=debug msg="completed challenge" Jan 19 15:28:36 music-player go-librespot[8160]: time="2025-01-19T15:28:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:36 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:36 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:36 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:36 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:39 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:39 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1388. Jan 19 15:28:39 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:39 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:39 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:39 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:39 music-player go-librespot[8195]: Librespot-go daemon starting... Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=info msg="generated new device id: c505b78ab104839bcb0f65e23600984ef2c54b0d" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="zeroconf server listening on port 34517" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="obtained new client token: AACFt3pqZqcJKPlAAKpL/VejoDVDjmqdqsGctH9ShJ+4mA8puVzT9NnIB0VXq48XE4zzFw+1LxNrxmDP3NfsVc+oyic2PfJ+tHhPDbEBNNSTOOZ4PoqZx54JNMoYQEVdIjELq1fKG2QwTXUaiUI6r9rZsPHWBHZSR07hhnNUcMXUZdejmcBm1f9FXCyybZopUAVlJulZQIIbJkI9op16LGR15j7vv4kJhkJ0l9jOWtDf7bWKBDaUU5F2/h51qyY=" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:39 music-player go-librespot[8195]: time="2025-01-19T15:28:39+01:00" level=debug msg="completed challenge" Jan 19 15:28:40 music-player go-librespot[8195]: time="2025-01-19T15:28:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:40 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:40 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:42 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:42 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:43 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:43 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1389. Jan 19 15:28:43 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:43 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:43 music-player go-librespot[8209]: Librespot-go daemon starting... Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=info msg="generated new device id: 433cc7d090f30d4742714088faae46f933ca98b2" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="zeroconf server listening on port 39303" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="obtained new client token: AADP0LauwMQOsGQ7Swex5EWYYcH/iG/3YovSlmya/WejTKps67wdz129BvrVVt9A/yXx4Xp1JCIvMVABGR6Y7scGRJnVc6smdMLcE8vAVUtdNI8ckPcHxZlmJs7G0mE6AcUHLLRpSeo9vx6rEAjSL+ly//ufoLmprI/NgvPKvFG0I1kBlfM23tvYfeomUs9Q9CHPBxRMPQMKbzza9wvL4DPYu0sh2nsHlzSP7D1724fLjhq1SO0/1YurQkv1U0o=" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:43 music-player go-librespot[8209]: time="2025-01-19T15:28:43+01:00" level=debug msg="completed challenge" Jan 19 15:28:44 music-player go-librespot[8209]: time="2025-01-19T15:28:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:44 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:44 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:45 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:45 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:47 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:47 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1390. Jan 19 15:28:47 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:47 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:47 music-player go-librespot[8225]: Librespot-go daemon starting... Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=info msg="generated new device id: 1129c08cbefcf13cef94d8b34e45a92c78ce86ba" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="zeroconf server listening on port 35639" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="obtained new client token: AAB2DOJlsErx+HKW+HvDxsamFWt5Vu998IA30RpqDfObChbu/ropSmmW/RYSIRHsYODszQZULitlYznL80hOCcSTuYhEwzXIjwRYKI5rpE3ZDTHu1wkEPL4k8S4dHOYtRKHZD+1MfSgq/x+5NI9+Z51+/nYZpxAQcgZHuXx5pkQ6XsWdCypdXokvFau3VZ6uzKUR0DQINQs1bAv4pamw+6p200bCopKGym6/RoovYN8om03bk6ovvbsO5EoB8AU=" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:47 music-player go-librespot[8225]: time="2025-01-19T15:28:47+01:00" level=debug msg="completed challenge" Jan 19 15:28:48 music-player go-librespot[8225]: time="2025-01-19T15:28:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:48 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:48 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:48 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:48 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:51 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:51 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1391. Jan 19 15:28:51 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:51 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:51 music-player go-librespot[8258]: Librespot-go daemon starting... Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=info msg="generated new device id: bfd3b81c05cb7d7f7f6015b5c88af00bdcf99eed" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:51 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:51 music-player volumio[5810]: info: Connection to go-librespot Websocket established Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="new websocket client" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="zeroconf server listening on port 34627" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="obtained new client token: AADpqQIP0Zm2EwATWaHsSXzOVnz4Wyig7n/zi05rG7vW6WVoSkbyzlywPYrxcISaAoBlv1JE4Q6rLkVE8xqRjnvTNIlJZxRRXJjXRoe/XTh3x2n15tmrRXjUKDlxiO7Sn9QaU25ZwiJD7T1dZWd+Wuen9eoYaHWvIRZo/sMRykBIoRVi+kX2Aj0UpJcgJOjl6Wyjz1GuqWD1E5ngNQu63AXLtceR75UNu1A7X3Wl+T4nlPSwSrUvSiHvzYbfcS0=" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:51 music-player go-librespot[8258]: time="2025-01-19T15:28:51+01:00" level=debug msg="completed challenge" Jan 19 15:28:52 music-player go-librespot[8258]: time="2025-01-19T15:28:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:52 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:52 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:52 music-player volumio[5810]: info: Connection to go-librespot Websocket closed Jan 19 15:28:54 music-player volumio[5810]: info: Getting Spotify volume Jan 19 15:28:54 music-player volumio[5810]: (node:5810) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:54 music-player volumio[5810]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 19 15:28:54 music-player volumio[5810]: (node:5810) 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: 223) Jan 19 15:28:54 music-player volumio[5810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 19 15:28:54 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:28:54 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:28:55 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:55 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:55 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:55 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1392. Jan 19 15:28:55 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:55 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:55 music-player go-librespot[8275]: Librespot-go daemon starting... Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=info msg="generated new device id: f9503a9f8b6dab5b947a7ca1d35bdcfca9639a0a" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="zeroconf server listening on port 40281" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="obtained new client token: AACMP8rhAW4NCnvFEe5ZPxfQYRBwn+QUCa0XvwZHBxqVD4C8cEFdR7YI8juWFAxr5bXmGASld3ByYczO8rd+XlaRMFugZpc1wdvDu7UOS5E4v8dH9fTNt9edITEsYqYOP21YDknfZk+y4Bh2cGEDHIqrRRVc9QPN5DCDKD6zAEyB1m0g/K/8GYuyWqqCUwXVs65iVLX8CPY6fNVnBVx30Rdhk4rM2COz47qy6RQVUPhm8xEy59FavB2cZJp95cQ=" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="completed keyexchange" Jan 19 15:28:55 music-player go-librespot[8275]: time="2025-01-19T15:28:55+01:00" level=debug msg="completed challenge" Jan 19 15:28:56 music-player go-librespot[8275]: time="2025-01-19T15:28:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:28:56 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:28:56 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:28:58 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:28:58 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:28:59 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:28:59 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1393. Jan 19 15:28:59 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:28:59 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:28:59 music-player go-librespot[8309]: Librespot-go daemon starting... Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=info msg="generated new device id: f7c7f4c99a10fdd43642c246523a2d3ad57f503f" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="zeroconf server listening on port 37389" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="obtained new client token: AAC+xby933diSUGsRqGyeFoVUD+RWn7SVuNlFYdRj/qxqZivtwrgBaLOGntdKCWbieO0SoYFxjWdfGZBx/qBVUJ9qiQZhjmfWRzbUjQqfZMdq9a8CcqAYu+McQiVUHT0dMdQTn0MAVXSEp6+ga7615b3fisqz1v4mw57XJqPj1Zhh9NmhywiyQYNRQulq8aLLo+yTPB97l99yJcIwKj4cNmACgvV22qOpXocZaIS2TqiQ1Mdy53jxgCaHARiAMY=" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:28:59 music-player go-librespot[8309]: time="2025-01-19T15:28:59+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:00 music-player go-librespot[8309]: time="2025-01-19T15:29:00+01:00" level=debug msg="completed challenge" Jan 19 15:29:00 music-player go-librespot[8309]: time="2025-01-19T15:29:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:00 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:00 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:01 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:01 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:03 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:03 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1394. Jan 19 15:29:03 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:03 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:03 music-player go-librespot[8326]: Librespot-go daemon starting... Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=info msg="generated new device id: 3036b7eb3cd68c4706bc81578455e85875482bee" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="zeroconf server listening on port 34727" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="obtained new client token: AAD/xdd2BQBFmWlIm3FJXjPLvlAt/wgWQJisVUPYOSMlXsiLD9g0LbOK6/qqx26mRSRHDdFTv3RCQoVOZ4omITgtCCtn0PKc1hCl9aYUAal8tCG9LymIOgZtcV1VAuj1rfwR+qSSAR6DN2siullBLx7SY3fMMV+iqqBEpwSwK1Vty9rniGwEH5BQ0YoF8B4n6eTKZnPZ3Jyqkq+Lzxz0qgt1Nx4Biaz8C7FP4Gj0Qyz/iDn5yCnIMB9xiK0OkjY=" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:03 music-player go-librespot[8326]: time="2025-01-19T15:29:03+01:00" level=debug msg="completed challenge" Jan 19 15:29:04 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:04 music-player go-librespot[8326]: time="2025-01-19T15:29:04+01:00" level=debug msg="new websocket client" Jan 19 15:29:04 music-player volumio[5810]: info: Connection to go-librespot Websocket established Jan 19 15:29:04 music-player go-librespot[8326]: time="2025-01-19T15:29:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:04 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:04 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:04 music-player volumio[5810]: info: Connection to go-librespot Websocket closed Jan 19 15:29:07 music-player volumio[5810]: info: Getting Spotify volume Jan 19 15:29:07 music-player volumio[5810]: (node:5810) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:07 music-player volumio[5810]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 19 15:29:07 music-player volumio[5810]: (node:5810) 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: 224) Jan 19 15:29:07 music-player volumio[5810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 19 15:29:07 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:07 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:07 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:29:07 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:07 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:07 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1395. Jan 19 15:29:07 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:07 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:07 music-player go-librespot[8342]: Librespot-go daemon starting... Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=info msg="generated new device id: 0e911187aa4524866d4c61163671cd71cbdc27e4" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="zeroconf server listening on port 40935" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="obtained new client token: AACjEyxQjcjY7uLdg08bXdgqR0CH3DJTpSI0xIsxflj+BPndSnQTvOuCcGV8fkCNrZLi85HAFFhKYLdqw05j7uDS+ERqmEk+RgHJC95FZjpRD/NlT6UK73wMM2Dn8xaxbo+oVVWQ00RCm24z1QGHbhPQUlDjiFhLqYgFF/A13FLon78MjkVnGMSxSgXQo/IDE61IJk9u/TWE98Cfipft9ilfRcfC+6n76pWP7VxhnMa/TSIZKAOvHAKzSiH1WDw=" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=debug msg="completed challenge" Jan 19 15:29:07 music-player go-librespot[8342]: time="2025-01-19T15:29:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:07 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:07 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:10 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:10 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:11 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:11 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1396. Jan 19 15:29:11 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:11 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:11 music-player go-librespot[8375]: Librespot-go daemon starting... Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=info msg="generated new device id: f3773799bddcbf0e91255a96a4b510ac25e34aac" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="zeroconf server listening on port 37901" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="obtained new client token: AADj97mkkT9cnvu6onCUo63d1GymbHZRMP+XV+/+Q+DHlACYMktmf07D5l5PVajC5uvrDVolvvQ6ve9E2/7Rmhqh8RyQ48N0KIBMdP2NQiRtni1fUGtHsIbZdJzavb/6pmUiLFrl8NNgyleFm9g3T3a5xrYKYohALoVez11NE5r4RBI5HyHwcqCcQ9eke5EeDIYopvydvk6QZiepPHC4+CVrmzkqnGp51tSyhPKoY0vIqgcRxzIv+m9/q2Ozp/M=" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=debug msg="completed challenge" Jan 19 15:29:11 music-player go-librespot[8375]: time="2025-01-19T15:29:11+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:11 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:11 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:13 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:13 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:13 music-player volumio[5810]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jan 19 15:29:13 music-player volumio[5810]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jan 19 15:29:13 music-player volumio[5810]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37) Jan 19 15:29:13 music-player volumio[5810]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Jan 19 15:29:13 music-player volumio[5810]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 19 15:29:13 music-player volumio[5810]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jan 19 15:29:13 music-player volumio[5810]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jan 19 15:29:13 music-player volumio[5810]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 19 15:29:13 music-player volumio[5810]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jan 19 15:29:13 music-player volumio[5810]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jan 19 15:29:13 music-player volumio[5810]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jan 19 15:29:13 music-player volumio[5810]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jan 19 15:29:13 music-player volumio[5810]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jan 19 15:29:13 music-player volumio[5810]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jan 19 15:29:13 music-player volumio[5810]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jan 19 15:29:13 music-player volumio[5810]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jan 19 15:29:14 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:14 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1397. Jan 19 15:29:14 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:15 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:15 music-player go-librespot[8393]: Librespot-go daemon starting... Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=info msg="generated new device id: 7aec5d1ee304d2f46302fad1808ad7516f0106a2" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="zeroconf server listening on port 33661" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="obtained new client token: AABB0p1WzCu62rf8+MYke/zEBV4pJJZei5BlEXjENq+Z74qXdo+mLaZMISp/MPiz3xyF7J7/im8Cr3wh6JzpAz++xBR9VIL98utSCzcIfO1FdqHT8qXhqFuyMgsd6Ll9e1DFiG6gIki0goOIwAkUn0RkceIkMHqviqWc5Jkzmbj61s8Jb2Z4Q9D8N8a0pyu+wGLlL8LwHqmV+C+a3nLcewKPFSS8rF6O/YsJ9g+O9ksTCG95JJnGbOjiqEW1+NI=" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:15 music-player go-librespot[8393]: time="2025-01-19T15:29:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed solving challenge: failed login: BadCredentials" Jan 19 15:29:15 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:15 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:15 music-player volumiologrotate[768]: ls: cannot access '/var/log/samba/log.wb-MUSIC': No such file or directory Jan 19 15:29:15 music-player volumiologrotate[768]: ls: cannot access 'PLAYER': No such file or directory Jan 19 15:29:16 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:16 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:18 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:18 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1398. Jan 19 15:29:18 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:18 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:18 music-player go-librespot[8468]: Librespot-go daemon starting... Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=info msg="generated new device id: a9e0e367d7258d13e1a90d11fd2a38d15b9946ba" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="zeroconf server listening on port 33401" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="obtained new client token: AAAfl7HgSN4yeWzggwOgUhAJSI18mcROX4Acgfur/TjCgz1MFCRKt4iqG229GcO2epAYdMbVNAblw5kMp+4eEcYXrnMGNJLllq7ZUeTpkArjXUTe30vfLCOFAevVsyRzzNcqhxSHggqhZ///lyGVbvFvw/GSnqd9WXK+MPOCjWUsWE8TsE2+a1ZBDrqHIm824xT4zozJYJLQfs3/IPS/8GTswBbVw7zslD9h2MwjoQZJYrNhF6H77HdDNTIOgXs=" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:18 music-player go-librespot[8468]: time="2025-01-19T15:29:18+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:19 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:19 music-player go-librespot[8468]: time="2025-01-19T15:29:19+01:00" level=debug msg="new websocket client" Jan 19 15:29:19 music-player volumio[5810]: info: Connection to go-librespot Websocket established Jan 19 15:29:19 music-player go-librespot[8468]: time="2025-01-19T15:29:19+01:00" level=debug msg="completed challenge" Jan 19 15:29:19 music-player go-librespot[8468]: time="2025-01-19T15:29:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:19 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:19 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:19 music-player volumio[5810]: info: Connection to go-librespot Websocket closed Jan 19 15:29:22 music-player volumio[5810]: info: Getting Spotify volume Jan 19 15:29:22 music-player volumio[5810]: (node:5810) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:22 music-player volumio[5810]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 19 15:29:22 music-player volumio[5810]: (node:5810) 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: 226) Jan 19 15:29:22 music-player volumio[5810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 19 15:29:22 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:22 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:29:22 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:22 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:22 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:22 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1399. Jan 19 15:29:22 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:22 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:22 music-player go-librespot[8485]: Librespot-go daemon starting... Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=info msg="generated new device id: 683a3b54b61908dd92dfa243a23654047bcf04cc" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="zeroconf server listening on port 38225" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="obtained new client token: AABE71RO56hxoOlwFhV4GVZi2PiOTBTaw1KvtdicJ5eI90Ic5gRTzUk9igu/Bka5pOoKPL2qXR6go0LY4MmsOPwhTu0Ftzj2pwR5yfjELOHmOqwSKmBujjSyHPfua0eU1d1fX7dbM+T2dE3p5b+fx3mQ9wlqh/CKW2FpKMKE7FugFrXmxKR+qYNL0jIoFwkqT9M53cBe2C/7j4yLFJE2b+z4/cK9D5DPzsvlBmgjgS0COHJvFdLCzhdZ+pP+b1Q=" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:22 music-player go-librespot[8485]: time="2025-01-19T15:29:22+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:23 music-player go-librespot[8485]: time="2025-01-19T15:29:23+01:00" level=debug msg="completed challenge" Jan 19 15:29:23 music-player go-librespot[8485]: time="2025-01-19T15:29:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:23 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:23 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:25 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:25 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:26 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:26 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1400. Jan 19 15:29:26 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:26 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:26 music-player go-librespot[8501]: Librespot-go daemon starting... Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=info msg="generated new device id: c1977b0b7a83672def706ec6114600ab60dd01e4" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="zeroconf server listening on port 36383" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="obtained new client token: AAAXhvb8O5hbyMTbbeKZujbUQd1wXQ1TGNMm34Amfw2GIQMcAm/MNL9NuHw9LgioYbfNpITEZgmhxC6f3AcxZJQEu/twxEFoANXQs6vQYjVT5R0I41vXQ8pte3rvl0g0DAlsyjCr3Fuihv9mJ/YvHNTYbk5nGHRHZny8/VtD8lIl9M1ebG5NCbrfflWD78iJnorUEvNJ0uJcPiLkFAlaJ4ymDyzARva4dIJT6rB4Z1hn7ZXy+FFNRGA+QkQoA0I=" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:26 music-player go-librespot[8501]: time="2025-01-19T15:29:26+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:27 music-player go-librespot[8501]: time="2025-01-19T15:29:27+01:00" level=debug msg="completed challenge" Jan 19 15:29:27 music-player go-librespot[8501]: time="2025-01-19T15:29:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:27 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:27 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:28 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:28 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:30 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:30 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1401. Jan 19 15:29:30 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:30 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:30 music-player go-librespot[8535]: Librespot-go daemon starting... Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=info msg="generated new device id: cd8b181a3749a9f44c595c5bae101a3c422b711c" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="zeroconf server listening on port 38915" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="obtained new client token: AAC2ZklqRYMs9QK36D3YVBB3zWOZvAvszo7RRpkyUjxaeqdz9CgjjtkUUeB8sPFJ3EbtzcPut4OYbc8ubalTg3563/EjhEdfsirQjTIZ/9WDWTYeNR3Kde251dSngH//eq3XpNtVR97DXghcWAXhcbNXQRMifd+zTbgz9isR3El6qsKMdzLmDfzdaI+wqNb3j672fP5sTdueWtgVHDByLGH147paaKosRnqG5VoIIxHvDRTr6KW8jBdH1s9DM5g=" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=debug msg="completed challenge" Jan 19 15:29:30 music-player go-librespot[8535]: time="2025-01-19T15:29:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:30 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:30 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:31 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:31 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:33 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:33 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1402. Jan 19 15:29:33 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:34 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:34 music-player go-librespot[8552]: Librespot-go daemon starting... Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=info msg="generated new device id: ab26221639eb198a910cbac225059f90f1c1fb57" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="zeroconf server listening on port 39381" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="obtained new client token: AABkcTK93cGM3ovlkf//hjK6M0gKsUQUfEwfbvrItM5XnpE1jPi4Jfu052rqvnua9HRYdZZg+GDEAfRZRLGEIcarCgU311DK8cVfk0ef1FSGxwLdgr1Jeh5MxASWzJqqU4CtZsfCS47vqRSasRGe+tYEoRXEE6nsAWVzWgI6FAPsK8tvw0JV8YdvHwgd20E80DggBY+MS20JYYGmh6HhRFPqydkTYbn8Fjf9yftbDCAZQ7+ItOu1IF+SVbaq" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:34 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="new websocket client" Jan 19 15:29:34 music-player volumio[5810]: info: Connection to go-librespot Websocket established Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=debug msg="completed challenge" Jan 19 15:29:34 music-player go-librespot[8552]: time="2025-01-19T15:29:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:34 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:34 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:34 music-player volumio[5810]: info: Connection to go-librespot Websocket closed Jan 19 15:29:37 music-player volumio[5810]: info: Getting Spotify volume Jan 19 15:29:37 music-player volumio[5810]: (node:5810) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:37 music-player volumio[5810]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 19 15:29:37 music-player volumio[5810]: (node:5810) 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: 227) Jan 19 15:29:37 music-player volumio[5810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 19 15:29:37 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:37 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:29:37 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:37 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:37 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:37 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1403. Jan 19 15:29:37 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:38 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:38 music-player go-librespot[8569]: Librespot-go daemon starting... Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=info msg="generated new device id: 2eb179f81440894128eb1cd26315cc4c5b7de2b0" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="zeroconf server listening on port 38287" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="obtained new client token: AACrYCv4zCR4tUbukOJf6paYDr1Z0kCkka/wkAiI+vXyQDNT7WhvUAgECtJStVT1n/bfWsaDMbI9Id273safpfWnJFTZ/Xo7SffRXDcPX0zPGtW9u8j0SDL5Eml5RNoM3hPrARr8iPW4vqb3+f7udCP2I6yfrKro0IQUVTk7c7v/znnZnptxlm0wOkngvyyiDAXwVyyOkrHLgvjquM87j6dkQpF7juNz1Q28dGu0oXMM018OfaJBG/55OzUe" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=debug msg="completed challenge" Jan 19 15:29:38 music-player go-librespot[8569]: time="2025-01-19T15:29:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:38 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:38 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:40 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:40 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:41 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:41 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1404. Jan 19 15:29:41 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:42 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:42 music-player go-librespot[8602]: Librespot-go daemon starting... Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=info msg="generated new device id: 89f749ffdf0021c79b95d9c6b4561a48f76e6bf0" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="zeroconf server listening on port 39121" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="obtained new client token: AAD0SGTJ+RZBN3V7QOTPHcfmr8LYh6tdWkvncOVmzejMC2hzCb3EFypGooAL0Y6N7krZZ+xbjBTWza2jeHXPc/mtncC6RnjCVic5uTLpTAxvH0/uhmFIR8qX9z1HJa4BLyaDUCD2aewBxiQJplkUoOmUyHcBBHT9r9RnNZWH8FUyhrZiYdgg21vAFt5HjB8LDptcB+PkMUiKLlvOxxuAMOR3YFy4M1lpAPe30K2z7RrwH3dSvX3t1YZt6j9S" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=debug msg="completed challenge" Jan 19 15:29:42 music-player go-librespot[8602]: time="2025-01-19T15:29:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:42 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:42 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:43 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:43 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:45 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:45 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1405. Jan 19 15:29:45 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:46 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:46 music-player go-librespot[8618]: Librespot-go daemon starting... Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=info msg="generated new device id: 7f2496bea98c901b1cfa01eccfcdc541c83f1be8" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="zeroconf server listening on port 35045" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="obtained new client token: AACChxUhUKYGYBJ+1SFg3TzIgeqmOALpBFUjMZ6451WoNQl4bV9i34BQPztQJ0QkG7Sn8Q57M0CQE8TmSZwHvhxP//5R2z4422DbDIi7YcCGmCh4NBbJTrWpYJmonJezL8xUlyv6fSjLZB0HuHBg3NtPWUEa8zuhEapyK+021dDSSvfz0WdHeJe47WOb4o3dn5maizYglXAFzrarV//zhICEG9BxB+kTLWHrTdVqqW/lWvpmJEc4sZGjmQu/" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=debug msg="completed challenge" Jan 19 15:29:46 music-player go-librespot[8618]: time="2025-01-19T15:29:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:46 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:46 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:46 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:46 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:49 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:49 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:49 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:49 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1406. Jan 19 15:29:49 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:50 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:50 music-player go-librespot[8651]: Librespot-go daemon starting... Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=info msg="generated new device id: 7db19eff4b68364701dcfcf38e68ee1ffcc37366" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="zeroconf server listening on port 44911" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="obtained new client token: AADemhW3jaUtpOnDAxfCnRVqHiYbtSDNGcf2sXRiqFXYchiDkatMukxrkBMyd52hD/t9SbMB7W+eg92hEmUre646VeOqvp6uutRQcfuBjLvN7pYVsqtQTUSeY7RYdtS1XG6MNAh6CjtIDFlvUYNZmUyLbgOTKX0I/nsA6ERVQ20akTf2UJIJ3LdpSUijsk0wUbjA9/mfZ2LmTB32Tt/9FZQwNH7e2Vr4HjUF6XkFMEXZTg6QJ7M23uHBC60u" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=debug msg="completed challenge" Jan 19 15:29:50 music-player go-librespot[8651]: time="2025-01-19T15:29:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:50 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:50 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:51 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:51 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:51 music-player volumio[5810]: verbose: New Socket.io Connection to 192.168.1.188:3000 from 192.168.1.9 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 19 15:29:51 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 19 15:29:52 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:52 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:53 music-player sudo[8671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 15:29:53 music-player sudo[8671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 15:29:53 music-player sudo[8671]: pam_unix(sudo:session): session closed for user root Jan 19 15:29:53 music-player sudo[8674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 15:29:53 music-player sudo[8674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 15:29:53 music-player sudo[8674]: pam_unix(sudo:session): session closed for user root Jan 19 15:29:53 music-player volumio[5810]: verbose: New Socket.io Connection to 192.168.1.188 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 15:29:53 music-player sudo[8677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 15:29:53 music-player sudo[8677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 15:29:53 music-player sudo[8677]: pam_unix(sudo:session): session closed for user root Jan 19 15:29:53 music-player sudo[8682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 15:29:53 music-player sudo[8682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 15:29:53 music-player sudo[8682]: pam_unix(sudo:session): session closed for user root Jan 19 15:29:53 music-player volumio[5810]: verbose: New Socket.io Connection to 192.168.1.188 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 15:29:53 music-player volumio[5810]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 19 15:29:53 music-player volumio[5810]: info: Received Get System Info Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:53 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:53 music-player volumio[5810]: info: Listing playlists Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 15:29:53 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 15:29:53 music-player systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 19 15:29:53 music-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1407. Jan 19 15:29:53 music-player systemd[1]: Stopped go-librespot Daemon. Jan 19 15:29:54 music-player systemd[1]: Started go-librespot Daemon. Jan 19 15:29:54 music-player go-librespot[8684]: Librespot-go daemon starting... Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=info msg="generated new device id: 7ce1603b54cbf01a3e1918261a6e31afcf25e866" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="stored credentials found for johnkooij" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="zeroconf server listening on port 33309" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="obtained new client token: AADeQwYG9jqwd5xKuQDAeB/0W9wNAHljLFXiZ6LOB0cCsR1fz/Ruu0fuQcD+Hdvqg+PwdBTyq/T3bT/lJxMz0VmeVuk+Zg89UvKGAq3jcDsw9QdCcm8Ho+pk4CUCh/RjRdcZ/HrGsZ8c/WxN8YvLZVYP9A5q7YfPcrVF//+YmTiVuBU5SxuiQGcbokVzvdIowRZ2QtSg7ZKp8Ws4T4W8p74ZepT8gR0nLzkPZNCZpVledD4roYbEyWWb5g1t" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="completed keyexchange" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=debug msg="completed challenge" Jan 19 15:29:54 music-player go-librespot[8684]: time="2025-01-19T15:29:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 19 15:29:54 music-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 19 15:29:54 music-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:55 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:55 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 15:29:55 music-player volumio[5810]: info: Received Get System Info Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:55 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:55 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:55 music-player volumio[5810]: info: Initializing connection to go-librespot Websocket Jan 19 15:29:55 music-player volumio[5810]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::volumioStop Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::stop Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::stPlaybackTimer Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::updateTrackBlock Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrackBlock Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::pushState Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::volumioPushState Jan 19 15:29:56 music-player volumio[5810]: info: MRS: Pushing multiroomSync output update for this device Jan 19 15:29:56 music-player volumio[5810]: info: MRS: Pushing multiroomSync output Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::serviceStop Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::serviceStop Jan 19 15:29:56 music-player volumio[5810]: info: [1737296996354] ControllerWebradio::stop Jan 19 15:29:56 music-player volumio[5810]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 15:29:56 music-player volumio[5810]: info: [ASDebug] CurState: stop PrevState: play Jan 19 15:29:56 music-player volumio[5810]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 19 15:29:56 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:29:56 music-player volumio[5810]: info: Jan 19 15:29:56 music-player volumio[5810]: ---------------------------- MPD announces state update: player Jan 19 15:29:56 music-player volumio[5810]: info: sendMpdCommand stop took 15 milliseconds Jan 19 15:29:56 music-player volumio[5810]: info: ControllerMpd::getState Jan 19 15:29:56 music-player volumio[5810]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:29:56 music-player volumio[5810]: info: sendMpdCommand status took 18 milliseconds Jan 19 15:29:56 music-player volumio[5810]: verbose: ControllerMpd::parseState Jan 19 15:29:56 music-player volumio[5810]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:29:56 music-player volumio[5810]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 19 15:29:56 music-player volumio[5810]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:29:56 music-player volumio[5810]: info: ControllerMpd::pushState Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::servicePushState Jan 19 15:29:56 music-player volumio[5810]: info: CoreStateMachine::pushState Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::volumioPushState Jan 19 15:29:56 music-player volumio[5810]: info: MRS: Pushing multiroomSync output update for this device Jan 19 15:29:56 music-player volumio[5810]: info: MRS: Pushing multiroomSync output Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 19 15:29:56 music-player volumio[5810]: info: ------------------------------ 31ms Jan 19 15:29:56 music-player volumio[5810]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 15:29:56 music-player volumio[5810]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"JOE Nederland","artist":"","uri":"https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein","service":""} Jan 19 15:29:56 music-player volumio[5810]: info: [ASDebug] CurState: stop PrevState: play Jan 19 15:29:56 music-player volumio[5810]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jan 19 15:29:56 music-player volumio[5810]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 15:29:56 music-player volumio[5810]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"JOE Nederland","uri":"https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein","service":""} Jan 19 15:29:56 music-player volumio[5810]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 15:29:56 music-player volumio[5810]: info: Received Get System Info Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 15:29:56 music-player volumio[5810]: info: Discovery: Getting this device information Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::volumioGetState Jan 19 15:29:56 music-player volumio[5810]: info: CorePlayQueue::getTrack 0 Jan 19 15:29:56 music-player volumio[5810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 15:29:57 music-player volumio[5810]: info: [ASDebug] Togle GPIO: OFF Jan 19 15:29:57 music-player volumio[5810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:29:57 music-player volumio[5810]: Error: ESPIPE: invalid seek, write Jan 19 15:29:57 music-player volumio[5810]: at Object.writeSync (fs.js:711:3) Jan 19 15:29:57 music-player volumio[5810]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jan 19 15:29:57 music-player volumio[5810]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jan 19 15:29:57 music-player volumio[5810]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jan 19 15:29:57 music-player volumio[5810]: at listOnTimeout (internal/timers.js:554:17) Jan 19 15:29:57 music-player volumio[5810]: at processTimers (internal/timers.js:497:7) { Jan 19 15:29:57 music-player volumio[5810]: errno: -29, Jan 19 15:29:57 music-player volumio[5810]: syscall: 'write', Jan 19 15:29:57 music-player volumio[5810]: code: 'ESPIPE' Jan 19 15:29:57 music-player volumio[5810]: } Jan 19 15:29:57 music-player volumio[5810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:29:57 music-player sudo[8721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 15:28 Jan 19 15:29:57 music-player sudo[8721]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="d316d397153fbb98c246d670c12f98a120b995a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 11 Jan 2025 10:58:50 AM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e6f1869148b01453dc48d3e2d79d1421"