-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Fri 2024-07-05 08:26:18 BST. -- Jul 05 08:25:00 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:00 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:00 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:00 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:01 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:01 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:01 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:01 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:02 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:02 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:02 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:02 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:03 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:03 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:03 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:03 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:04 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:04 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:04 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:04 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:05 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:05 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:05 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:05 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:06 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:06 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:06 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:06 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:07 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:07 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:07 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:07 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:08 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:08 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:09 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:09 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:09 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:09 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:10 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:10 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:10 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:10 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:11 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:11 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:12 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:12 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:12 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:12 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:13 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:13 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:13 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:13 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:14 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:14 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:14 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:14 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:15 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:15 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:15 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:15 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:16 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:16 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:16 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:16 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:17 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:17 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:17 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:17 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:18 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:18 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:18 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:18 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:19 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:19 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:19 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:19 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:20 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:20 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:20 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:20 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:21 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:21 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:21 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:21 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:22 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:22 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:22 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:22 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:23 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:23 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:23 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:23 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:24 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:24 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:24 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:24 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:25 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:25 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:25 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:25 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:26 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:26 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:26 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:26 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:27 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:27 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:27 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:27 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:28 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:28 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:28 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:28 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:29 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:29 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:29 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:29 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:30 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:30 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:30 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:30 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:31 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:31 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:31 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:31 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:32 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:32 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:32 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:32 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:33 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:33 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:33 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:33 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:34 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:34 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:34 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:34 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:35 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:35 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:35 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:35 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:36 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:36 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:36 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:36 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:37 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:37 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:37 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:37 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:37 masterbedroom go-librespot[5627]: time="2024-07-05T08:25:37+01:00" level=error msg="did not receive last pong ack from accesspoint, 120s passed" Jul 05 08:25:37 masterbedroom go-librespot[5627]: time="2024-07-05T08:25:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.11.104:49938->104.199.65.124:4070: use of closed network connection" Jul 05 08:25:37 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:37 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:37 masterbedroom volumio[1081]: (node:1081) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 05 08:25:37 masterbedroom volumio[1081]: at connResetException (internal/errors.js:607:14) Jul 05 08:25:37 masterbedroom volumio[1081]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 05 08:25:37 masterbedroom volumio[1081]: at Socket.emit (events.js:327:22) Jul 05 08:25:37 masterbedroom volumio[1081]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 05 08:25:37 masterbedroom volumio[1081]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 08:25:37 masterbedroom volumio[1081]: (node:1081) 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: 33) Jul 05 08:25:37 masterbedroom volumio[1081]: info: Connection to go-librespot Websocket closed Jul 05 08:25:38 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:38 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:38 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:38 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:39 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:39 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:39 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:39 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:40 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:40 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:40 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:40 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:40 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:40 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:41 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:25:41 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 334. Jul 05 08:25:41 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:25:41 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:25:41 masterbedroom go-librespot[5814]: Librespot-go daemon starting... Jul 05 08:25:41 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:41 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:41 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:41 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=info msg="generated new device id: a94a0a16c637e424cd7563e727788fa1f9ab2742" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=debug msg="obtained new client token: AAAK3T1O5hI98KMh5wFn0ZqJMWF4i/dpDJYQXX5NQiNiKb20435kqt42gXlB0gqmt3kMWoHMZ2J84cDC60FMT9cMgxjW/8Z3DO7Q/tVAZSPc0aWARo+B5CcDY+2TWoqLRh3IP3ZS5R6ApkheNkYEdDldvmDKRHUN6cbv8IQ1dh/U4Hlh2fAM9pSSbcQaXGIvX5xZf83epwymybfq+BS2T2673QN61USQn3w5LHEw3RJKfiAJZLYCYSW/0F4M" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=debug msg="completed keyexchange" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=debug msg="completed challenge" Jul 05 08:25:41 masterbedroom go-librespot[5814]: time="2024-07-05T08:25:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:25:41 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:41 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:42 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:42 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:42 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:42 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:43 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:43 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:43 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:43 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:43 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:43 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:44 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:44 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:44 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:44 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:45 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:25:45 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 335. Jul 05 08:25:45 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:25:45 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:25:45 masterbedroom go-librespot[5822]: Librespot-go daemon starting... Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=info msg="generated new device id: 5adc356737baab452c4d9bcd19246a0c9922503f" Jul 05 08:25:45 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:45 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:45 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:45 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=debug msg="obtained new client token: AAAMybA7wb8tQgWvqh4ZBtjqofxjE1tEsEJBZFnVR/ZdHMPBaCHVy5jSWrV1NF0vA1HuZMyWSC3h+jV034DYFwMk9ObgKuN5Xbk4kcPEd4EM+2FfyDdZriH9KvGSjY8u6oghQSr8gfaz8Ej4ZDTORpoKVUg9cWjcfwba2rBNZguMgvGXVaDwHeo2nufJxNRE4HKpCYBzzunE60PU+NH53LwDYJ2Nhhz3hZ8kwOFDOBJPXpOlIYSLi46+0g==" Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=debug msg="completed keyexchange" Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=debug msg="completed challenge" Jul 05 08:25:45 masterbedroom go-librespot[5822]: time="2024-07-05T08:25:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:25:45 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:45 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:46 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:46 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:46 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:46 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:46 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:46 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:47 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:47 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:47 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:47 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:48 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:48 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:48 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:48 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:49 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:25:49 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 336. Jul 05 08:25:49 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:25:49 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:25:49 masterbedroom go-librespot[5830]: Librespot-go daemon starting... Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=info msg="generated new device id: 365cd69c3a50db2a82afde1887271a6f76c83f95" Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:25:49 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:49 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:49 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:49 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="obtained new client token: AACGxVH/11Fpax51t4pRQM++r6V1iK5rj8SDcbu7q4kuWcirUefTm50y+xa4HXaLf0lNDmQZROUUTbyiiDykQykhCG8LjPkIk9AbMv77RIu7EA4gKLtaAPkZxLfl2S4TClGBCYlE/bmx9hn01fVvqsjiuEwL2I3qu3E8t3Pgsn/jrSCRAkhIEfFW0Huere9t6WOd94KXBtmWNz11nzg9tbcpnDAgzzr2BYSnKsPLB+3l3UtACjCkYF3zGw==" Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="completed keyexchange" Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="completed challenge" Jul 05 08:25:49 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=debug msg="new websocket client" Jul 05 08:25:49 masterbedroom volumio[1081]: info: Connection to go-librespot Websocket established Jul 05 08:25:49 masterbedroom go-librespot[5830]: time="2024-07-05T08:25:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:25:49 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:49 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:49 masterbedroom volumio[1081]: info: Connection to go-librespot Websocket closed Jul 05 08:25:50 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:50 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:50 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:50 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:51 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:51 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:51 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:51 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:52 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:52 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:52 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:52 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:52 masterbedroom volumio[1081]: info: Getting Spotify volume Jul 05 08:25:52 masterbedroom volumio[1081]: (node:1081) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:52 masterbedroom volumio[1081]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 05 08:25:52 masterbedroom volumio[1081]: (node:1081) 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: 34) Jul 05 08:25:52 masterbedroom volumio[1081]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 05 08:25:52 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:52 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:52 masterbedroom volumio[1081]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jul 05 08:25:52 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:53 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:25:53 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 337. Jul 05 08:25:53 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:25:53 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:25:53 masterbedroom go-librespot[5839]: Librespot-go daemon starting... Jul 05 08:25:53 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=info msg="generated new device id: 38443489fc9bc6b74017a2d93136102803fee25a" Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:25:53 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:53 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:53 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=debug msg="obtained new client token: AAA0MT7AN+DjGkNL1ses0K0MWEfW6MFiT9NHkTrKpekOYALkECpR2rDLNMiUG3rKYrtxtQ9ZOY/9yCOn1BVXj3BNvXVj6egcTQcUXdzThk3FrLEG4lfnIVsTJChEgijO2i0JH0tbk+fYdOhID9lCvGiS3kAJXAzRn/GUeGL9ifR3eeUv0BhxoOMbvlnlqdlKxXv5UXwgeQunOMRlYYT4eI7IJkNzofxZ9YIKr6q0mTkZulC/5/gcZ250CQ==" Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=debug msg="completed keyexchange" Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=debug msg="completed challenge" Jul 05 08:25:53 masterbedroom go-librespot[5839]: time="2024-07-05T08:25:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:25:53 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:53 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:54 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:54 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:54 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:54 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:55 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:55 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:55 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:55 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:55 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:55 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:56 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:56 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:56 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:56 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:57 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:25:57 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 338. Jul 05 08:25:57 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:25:57 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:25:57 masterbedroom go-librespot[5847]: Librespot-go daemon starting... Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=info msg="generated new device id: d97c1f0dd9494c997ee033e25d7361953434e300" Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:25:57 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:57 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:57 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:57 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=debug msg="obtained new client token: AAAtypdVFzYo3cdxZP06OiWJ7zhnRUfmmoHwV14uZf1dLT9V1IqX1HCstNquGbPGNMfZf42vTZxdGwKO7HeE8yoebIyFr2KzKHTpOZbce+hhanz5iTcRXS9XkXGtsnsjzFbWvSK6ZPuX8rEYfiH8VNBrEmCyCoDEMNb1ut5ZjXgjS/wVBW6L2nkaz8IMDBsnlG7Bh6H0ff9R5Pw6IDrclkdhr3kqQ0F8fbsCJMwYrEp8rhy2XftjWzdBdA==" Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=debug msg="completed keyexchange" Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=debug msg="completed challenge" Jul 05 08:25:57 masterbedroom go-librespot[5847]: time="2024-07-05T08:25:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:25:57 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:25:57 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:25:58 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:58 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:58 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:58 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:25:58 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:25:58 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:25:59 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:25:59 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:25:59 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:25:59 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:00 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:00 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:00 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:00 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:01 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:26:01 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 339. Jul 05 08:26:01 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:26:01 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:26:01 masterbedroom go-librespot[5856]: Librespot-go daemon starting... Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=info msg="generated new device id: b114a346d67b83dfce018c019446b4b74e6b58e9" Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:26:01 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:01 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:01 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:01 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="obtained new client token: AAAdOEmK38CaFXFdI1kUp/dg+MkQzzpOLxWgh+9BAg2aaizhUm4b5mJHor13O1FZYuGS8DYShja0EIgUWJC9zeHdOFmvL9t1d94Lk1q2Houg2UHp6epiwKKPJQPvPxFxcZE+rjlWGEeOlWHyr4Aeq2lus9UmsAklF/RVywTafVl84UBSaoM3urQMMoLJqPxhWKmN74viS+tBQqAFpS82cYuagPzSeHPs0X6mtzOf5pMqIWCzuglNFOwQ4Q==" Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="completed keyexchange" Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="completed challenge" Jul 05 08:26:01 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=debug msg="new websocket client" Jul 05 08:26:01 masterbedroom volumio[1081]: info: Connection to go-librespot Websocket established Jul 05 08:26:01 masterbedroom go-librespot[5856]: time="2024-07-05T08:26:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:26:01 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:26:01 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:26:01 masterbedroom volumio[1081]: info: Connection to go-librespot Websocket closed Jul 05 08:26:02 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:02 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:02 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:02 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:03 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:03 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:03 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:03 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:04 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:04 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:04 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:04 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:04 masterbedroom volumio[1081]: info: Getting Spotify volume Jul 05 08:26:04 masterbedroom volumio[1081]: (node:1081) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:04 masterbedroom volumio[1081]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 05 08:26:04 masterbedroom volumio[1081]: (node:1081) 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: 35) Jul 05 08:26:04 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:04 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:04 masterbedroom volumio[1081]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 05 08:26:04 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:04 masterbedroom volumio[1081]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jul 05 08:26:05 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:26:05 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 340. Jul 05 08:26:05 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:26:05 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:26:05 masterbedroom go-librespot[5864]: Librespot-go daemon starting... Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=info msg="generated new device id: 90e1fe6009c2156f0b181ffbda7fd65edc11176f" Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:26:05 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:05 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:05 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:05 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=debug msg="obtained new client token: AACJAKUO7Y+ImmeBs0ZkXTxA1Xz1X/dLD7i/w+qgS0stmGgAl5lje+nt+5hqOtJJw70j84vV6JlBEXa1luWFs7i07f7cUgw0fVGi+YS3hywoR7YRoS6qYijJ8LIwV8X/8z4UsuCc9hwi3sXwVLyZwSpSGrrfxy20YpzTYO241hSvRTns0RSoSr2dbPXbi0ezH87+ddxFIseM9ZBvB4ZZjUh24MZkfEDm3zjlMHwVv+wbCPO4xw87YI0Mgw==" Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=debug msg="completed keyexchange" Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=debug msg="completed challenge" Jul 05 08:26:05 masterbedroom go-librespot[5864]: time="2024-07-05T08:26:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:26:05 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:26:05 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:26:06 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:06 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:06 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:06 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:07 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:07 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:07 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:07 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:07 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:07 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:07 masterbedroom volumio[1081]: verbose: New Socket.io Connection to 192.168.11.105 from 192.168.11.117 UA: unknown Engine version: 3 Transport: websocket Total Clients: 8 Jul 05 08:26:07 masterbedroom volumio[1081]: verbose: New Socket.io Connection to 192.168.11.104 from 192.168.11.117 UA: unknown Engine version: 3 Transport: websocket Total Clients: 9 Jul 05 08:26:07 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 08:26:08 masterbedroom volumio[1081]: info: Discovery: Getting this device information Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:08 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 08:26:08 masterbedroom volumio[1081]: info: Discovery: Getting this device information Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:08 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:26:08 masterbedroom volumio[1081]: verbose: New Socket.io Connection to 192.168.11.104 from 192.168.11.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Jul 05 08:26:09 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:26:09 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 341. Jul 05 08:26:09 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:26:09 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:26:09 masterbedroom go-librespot[5872]: Librespot-go daemon starting... Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=info msg="generated new device id: bbd02ea8d1c6112467f85a47c720b53aa0654eb4" Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:26:09 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:09 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:09 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:09 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=debug msg="obtained new client token: AADJdIto4P07pENuY1bc2xUSWJHB7/DUH0xd1NJ/05WK1V2Vdq3FFG03TiqWzy4HtQCZHp8E6+Exfr8K7uL/nYfxSjA6j1HyISRhUvJhcVhLISz6D36JILxv9zqxeCOk8WHQWsYOV7s6dnEfO82C4u6YW4WwkRGxOTyO2A3vla+Y+XB+9TWVTB3D+7YAaIdke/Vo+fKIDhdqiG8gqB0Tyiky7YeOOWFZlto/PlDGyvh5jaFeRarqnNOyWSlG" Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=debug msg="completed keyexchange" Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=debug msg="completed challenge" Jul 05 08:26:09 masterbedroom go-librespot[5872]: time="2024-07-05T08:26:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:26:09 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:26:09 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:26:10 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:10 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:10 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:10 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:10 masterbedroom sudo[5881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 08:26:10 masterbedroom sudo[5881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:26:10 masterbedroom sudo[5881]: pam_unix(sudo:session): session closed for user root Jul 05 08:26:10 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:10 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:10 masterbedroom sudo[5884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 08:26:10 masterbedroom sudo[5884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:26:10 masterbedroom sudo[5884]: pam_unix(sudo:session): session closed for user root Jul 05 08:26:10 masterbedroom volumio[1081]: verbose: New Socket.io Connection to 192.168.11.104 from 192.168.11.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 08:26:11 masterbedroom volumio[1081]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 05 08:26:11 masterbedroom volumio[1081]: info: Received Get System Info Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 08:26:11 masterbedroom volumio[1081]: info: Discovery: Getting this device information Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:11 masterbedroom volumio[1081]: info: Listing playlists Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:11 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:11 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:12 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 08:26:12 masterbedroom volumio[1081]: info: Received Get System Info Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 08:26:12 masterbedroom volumio[1081]: info: Discovery: Getting this device information Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:12 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:26:13 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:26:13 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 342. Jul 05 08:26:13 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:26:13 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:26:13 masterbedroom go-librespot[5886]: Librespot-go daemon starting... Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=info msg="generated new device id: e57bb7095e053ddb6e4ee3ec740968e7b7bf9144" Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:13 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=debug msg="obtained new client token: AABU6KX98zTam+vvVEgbiOFtaDraK8yTpjZjL6MrKkoNTGfvwlXtGGK1vwmXzT3MMoutCz+TtcyPBd4M18VRhvxxJzILxYShk68f6xhz+xdltUL7wOK/v9mwuvsLfW/KQh55QmbJFK4i/xRh4G9WXOOz6IJM6b9sA/sOWBqbma2LEyr0dt2yGyt6Wqw0fM823H2ELOPKK4NXV5UIAYKGj5XZU5YYM/4J+q79NOk2XM5zuycv2ObW6TWXiA==" Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=debug msg="completed keyexchange" Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 08:26:13 masterbedroom volumio[1081]: info: Received Get System Info Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 08:26:13 masterbedroom volumio[1081]: info: Discovery: Getting this device information Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:13 masterbedroom volumio[1081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=debug msg="completed challenge" Jul 05 08:26:13 masterbedroom go-librespot[5886]: time="2024-07-05T08:26:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:26:13 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:26:13 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:26:13 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:13 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:14 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:14 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:14 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:14 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:15 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:15 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:15 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:15 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:16 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:16 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:16 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:16 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:16 masterbedroom volumio[1081]: info: Initializing connection to go-librespot Websocket Jul 05 08:26:16 masterbedroom volumio[1081]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 05 08:26:17 masterbedroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 05 08:26:17 masterbedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 343. Jul 05 08:26:17 masterbedroom systemd[1]: Stopped go-librespot Daemon. Jul 05 08:26:17 masterbedroom systemd[1]: Started go-librespot Daemon. Jul 05 08:26:17 masterbedroom go-librespot[5894]: Librespot-go daemon starting... Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=info msg="generated new device id: fc89c1f8b74ad58a31e36811a2bfafc948de065c" Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=debug msg="stored credentials found for arbitrabbit" Jul 05 08:26:17 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetState Jul 05 08:26:17 masterbedroom volumio[1081]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:26:17 masterbedroom volumio[1081]: info: CoreStateMachine::getQueue Jul 05 08:26:17 masterbedroom volumio[1081]: info: CorePlayQueue::getQueue Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=debug msg="obtained new client token: AADz629IPTt3qiUGlbNP2ofrzHi0ociVIta49w5iImdu+zjiM9XvQNVqYpIG/5WQrXUyod8LV0bp5IA3w6+I4DH+JdolnFA/+jTUFWUZoiJf7WhwdE0HWrcRWueQWBh2aPO1AoRQaZ5mScqkg5vUeTcWhu5P6hKOXbWA0hEiBsZENYFOKMTmAXX2nuoSTy7JyzSZc65WS+RFIKmb1Eu6Ex5leNkK8EXyUPWt2bNo9A+o2Xv0g+Z7x7cHeQ==" Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=debug msg="completed keyexchange" Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=debug msg="completed challenge" Jul 05 08:26:17 masterbedroom go-librespot[5894]: time="2024-07-05T08:26:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jul 05 08:26:17 masterbedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 05 08:26:17 masterbedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 05 08:26:18 masterbedroom volumio[1081]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 05 08:26:18 masterbedroom volumio[1081]: info: PLUGIN onReboot : networkfs Jul 05 08:26:18 masterbedroom volumio[1081]: info: PLUGIN onReboot : audiophonicsonoff Jul 05 08:26:18 masterbedroom volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 08:26:18 masterbedroom volumio[1081]: TypeError: Cannot read property 'writeSync' of undefined Jul 05 08:26:18 masterbedroom volumio[1081]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 05 08:26:18 masterbedroom volumio[1081]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 05 08:26:18 masterbedroom volumio[1081]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 05 08:26:18 masterbedroom volumio[1081]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 05 08:26:18 masterbedroom volumio[1081]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 05 08:26:18 masterbedroom volumio[1081]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 05 08:26:18 masterbedroom volumio[1081]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 05 08:26:18 masterbedroom volumio[1081]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:860:33) Jul 05 08:26:18 masterbedroom volumio[1081]: at Socket.emit (events.js:315:20) Jul 05 08:26:18 masterbedroom volumio[1081]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 05 08:26:18 masterbedroom volumio[1081]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 05 08:26:18 masterbedroom volumio[1081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 08:26:18 masterbedroom sudo[5912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 08:25 Jul 05 08:26:18 masterbedroom sudo[5912]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="905ee02fb5387537cfa16b84ebd7f14b7640848e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 04 Jul 2024 02:29:26 AM CEST" VOLUMIO_VERSION="3.735" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="c3549df9b4fb845b268fd7accf565d01"