-- Logs begin at Mon 2025-06-16 20:30:32 BST, end at Fri 2025-07-18 20:55:01 BST. -- Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7436, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7448, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7456, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7463, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7470, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7477, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7484, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7491, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7501, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7511, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7518, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7525, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7532, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7539, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7546, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7556, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:00 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7566, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7573, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7581, instance 1, run: true Jul 18 20:54:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7588, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7595, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7602, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7614, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7622, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7629, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7636, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7643, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7650, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7657, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7669, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7677, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7684, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7694, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7702, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7709, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7716, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:01 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7730, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7737, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7744, instance 1, run: true Jul 18 20:54:01 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7751, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7758, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7765, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7772, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:02 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 193. Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7786, instance 1, run: true Jul 18 20:54:02 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:02 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:02 richdacvolumio go-librespot[7775]: Librespot-go daemon starting... Jul 18 20:54:02 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:02+01:00" level=info msg="generated new device id: 3509a63e8358fc9032a491b02750afbbaa6d731d" Jul 18 20:54:02 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:02+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7799, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7806, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7813, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7822, instance 1, run: true Jul 18 20:54:02 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:02+01:00" level=debug msg="obtained new client token: AAAmkr1HomXeP1rA+s5fYDgGOipRNIhuJL6v5Rd37Wy85yMEpmEdAWxSgSLGZBk7zw+ioiMcI4hbC20mxhmFIDi0qmZpKlXNiHtZERV+ajMkRPeLMPaS0g+reyf2ZAN7CRAx7QREfUvIlal1I1SJQp+3InSmJ71eV628Daho7i1uDKClEIHp8s+fDuwWxjkhB8faU4JUz9OLS9lSNITtLYQtBo+J9102bCRQGldtwNIK7UlrGIbMqgx62KboTg==" Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7829, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7836, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7849, instance 1, run: true Jul 18 20:54:02 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:02+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 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7856, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7863, instance 1, run: true Jul 18 20:54:02 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:02+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7870, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7877, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7884, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7891, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:02 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7904, instance 1, run: true Jul 18 20:54:02 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7911, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7918, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7925, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7932, instance 1, run: true Jul 18 20:54:03 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54:03+01:00" level=debug msg="completed challenge" Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7939, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio go-librespot[7775]: time="2025-07-18T20:54: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" Jul 18 20:54:03 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:03 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7952, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7959, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7966, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7973, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7980, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7987, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 7994, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8007, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8014, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8021, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8028, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:03 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8035, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8042, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8049, instance 1, run: true Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:03 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:03 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8062, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8069, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8076, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8083, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8090, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8097, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8104, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8116, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8124, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8131, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8138, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8145, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8152, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8159, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8171, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8179, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8186, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8193, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8200, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8207, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8214, instance 1, run: true Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:04 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8227, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8234, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8241, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8248, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8255, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8262, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8269, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8282, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8289, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8296, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8303, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8310, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8317, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8324, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:05 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8337, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8344, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8351, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8358, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8365, instance 1, run: true Jul 18 20:54:05 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8372, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8379, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8392, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8399, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8406, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8413, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8420, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8427, instance 1, run: true Jul 18 20:54:06 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:06 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 194. Jul 18 20:54:06 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8435, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:06 richdacvolumio go-librespot[8434]: Librespot-go daemon starting... Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8448, instance 1, run: true Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+01:00" level=info msg="generated new device id: 45368f4842c617d5d10e629282c05b8e2bd7bddc" Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8461, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8468, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8475, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8482, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8490, instance 1, run: true Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+01:00" level=debug msg="obtained new client token: AAB5y1vu7rDc/kJf9clCAIKhx6K41kcgvzoKf04ZTbfvTzaBQa7dq2yvEpS0WHmHJZ16izYEpuZs5p/RUg+KEIVdDRm9nyj6yJKfX8ZuOY7y7OpoIUp40SpNXBfFmfoPXc7HD5dZHRoGbrGrf4vZDmtzQYgngofFhQb7ATK6L6F3fPGU/C61DpX80c9QZ1qoLy/adPiSyOfy8fE4aZrLJ5WS1WcZNfuk2+062sNNpv99WUSAIuKxt53mzzOAqg==" Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8497, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:06 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:06 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+01:00" level=debug msg="new websocket client" Jul 18 20:54:06 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket established Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8509, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8517, instance 1, run: true Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+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 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8524, instance 1, run: true Jul 18 20:54:06 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8531, instance 1, run: true Jul 18 20:54:06 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:06+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8538, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8545, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8552, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8564, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8572, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8579, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8586, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8593, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8600, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8621, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54:07+01:00" level=debug msg="completed challenge" Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8634, instance 1, run: true Jul 18 20:54:07 richdacvolumio go-librespot[8434]: time="2025-07-18T20:54: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" Jul 18 20:54:07 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:07 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket closed Jul 18 20:54:07 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8641, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8648, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8655, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8662, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8669, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8676, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:07 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8689, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8696, instance 1, run: true Jul 18 20:54:07 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8703, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8710, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8717, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8724, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8731, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8744, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8751, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8758, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8765, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8772, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8779, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8786, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8799, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8806, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8813, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8820, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8827, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8834, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8841, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:08 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8854, instance 1, run: true Jul 18 20:54:08 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8861, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8868, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8875, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8882, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8889, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8896, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8909, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8916, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8923, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8930, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8937, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8944, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8951, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8964, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8971, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8978, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8985, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: Getting Spotify volume Jul 18 20:54:09 richdacvolumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:09 richdacvolumio volumio[1018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 18 20:54:09 richdacvolumio volumio[1018]: (node:1018) 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: 62) Jul 18 20:54:09 richdacvolumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8992, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 8999, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9006, instance 1, run: true Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:09 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9019, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9026, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9033, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9040, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9047, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9054, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9061, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9074, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9081, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9088, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9095, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9102, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:10 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9109, instance 1, run: true Jul 18 20:54:10 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:10 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 195. Jul 18 20:54:10 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9117, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:10 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:10 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:10 richdacvolumio go-librespot[9116]: Librespot-go daemon starting... Jul 18 20:54:10 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:10+01:00" level=info msg="generated new device id: 0fe5d0a94dd9e5dc9355d4203d4a433cb4097466" Jul 18 20:54:10 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:10+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9129, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9143, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9150, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9157, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9165, instance 1, run: true Jul 18 20:54:10 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9172, instance 1, run: true Jul 18 20:54:10 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:10+01:00" level=debug msg="obtained new client token: AABEZKggxHjcz+2XI867dEABZi0Ky3CVlwb4JfcDULbRGGRvL/p2hVS2PrU0FvBgILRRlZVytvk8EEP1pFLYdi7ib+DhfBg2yplxU9w0MRkng5HzXvLnBdVZQ8FDShDvZxwlv4etxmYpt5izSK1JP4nmuaP0JFXH8xQtwtwv4q5luh7PR2fJ+pIe+tI1lSc9Uq5+U38W55d2PGGLngCCvzLik8GkkcJt030JAQ+N8oibkessdu+S/umohpvzIA==" Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9179, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9192, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9199, instance 1, run: true Jul 18 20:54:11 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:11+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 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9206, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9213, instance 1, run: true Jul 18 20:54:11 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:11+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9220, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9227, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9234, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9247, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9254, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9261, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9268, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9275, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9282, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9289, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:11 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:11 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54:11+01:00" level=debug msg="completed challenge" Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9302, instance 1, run: true Jul 18 20:54:11 richdacvolumio go-librespot[9116]: time="2025-07-18T20:54: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" Jul 18 20:54:11 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:11 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9309, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9316, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9323, instance 1, run: true Jul 18 20:54:11 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9330, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9337, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9344, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9357, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9364, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9371, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9378, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9385, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9392, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9399, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9411, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9419, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9426, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9433, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9440, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9447, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9454, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:12 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9467, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9474, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9481, instance 1, run: true Jul 18 20:54:12 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9488, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9495, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9502, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9509, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9521, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9529, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9536, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9543, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9550, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9557, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9564, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9575, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:13 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9584, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9591, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9598, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9605, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9612, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9619, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:13 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9630, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9639, instance 1, run: true Jul 18 20:54:13 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9646, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9653, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9660, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9667, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9674, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9684, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9694, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9701, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9708, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9715, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9722, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9729, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9740, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9749, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9756, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9763, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9770, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9780, instance 1, run: true Jul 18 20:54:14 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:14 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 196. Jul 18 20:54:14 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9788, instance 1, run: true Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:14 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:14 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:14 richdacvolumio go-librespot[9787]: Librespot-go daemon starting... Jul 18 20:54:14 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:14+01:00" level=info msg="generated new device id: 9ee00c8e67c7fab4c9bed8261f1d20eac6791073" Jul 18 20:54:14 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:14+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:14 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9807, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9814, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9821, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9829, instance 1, run: true Jul 18 20:54:15 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:15+01:00" level=debug msg="obtained new client token: AACRYF+Q/lZO0zsm0di0d7WJM+gf9hYDL7y9lb4AvQjs7+oAKqJ6BddfQEzBYwUNfqa0BeTrrWyl7WfBZddsD/QS4D7osyAfKHy3Jk7kt2ljPIsfL5huO82kqQ42vHJ8+8laBZxZa9zqW3QbCQQzjDJBLGLrWrbIsSlAO79/sqxNzx1s0EJ8keyyZYr2aX0lvYytDP0cqrhL26VeVjPYHmw0yM3r0nl0EmZTOkgNwyo1gEVyYyDqX7xRtas=" Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9836, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9843, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9853, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9863, instance 1, run: true Jul 18 20:54:15 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:15+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 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9870, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9877, instance 1, run: true Jul 18 20:54:15 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:15+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9884, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9891, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9898, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9908, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9918, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9925, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9932, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9939, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9946, instance 1, run: true Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:15 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:16 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54:16+01:00" level=debug msg="completed challenge" Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9955, instance 1, run: true Jul 18 20:54:16 richdacvolumio go-librespot[9787]: time="2025-07-18T20:54: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" Jul 18 20:54:16 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:16 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9966, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9974, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:16 richdacvolumio systemd[1]: Starting Cleanup of Temporary Directories... Jul 18 20:54:16 richdacvolumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jul 18 20:54:16 richdacvolumio systemd[1]: Started Cleanup of Temporary Directories. Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9981, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9988, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 9995, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10002, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10013, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10022, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10029, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10036, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10043, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:16 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10050, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10057, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:16 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10067, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10077, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10084, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10091, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10098, instance 1, run: true Jul 18 20:54:16 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10105, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10112, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10123, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10132, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10139, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10146, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10153, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10160, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10167, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10180, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10200, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10208, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10215, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10222, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10229, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10236, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:17 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10249, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10256, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10263, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10270, instance 1, run: true Jul 18 20:54:17 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10277, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10284, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10291, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10304, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10311, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10318, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10325, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10332, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10339, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10346, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10359, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10366, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10373, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10380, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10387, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10394, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10401, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:18 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10414, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10421, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10428, instance 1, run: true Jul 18 20:54:18 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10435, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10442, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10449, instance 1, run: true Jul 18 20:54:19 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:19 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 197. Jul 18 20:54:19 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10457, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:19 richdacvolumio go-librespot[10456]: Librespot-go daemon starting... Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+01:00" level=info msg="generated new device id: fa8eec0936576972839b6da0fb6aee8ecec77489" Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10476, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10483, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10490, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10497, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10504, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10513, instance 1, run: true Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+01:00" level=debug msg="obtained new client token: AADyDIXB1bKeOJ9uUA8ci777gNML2/67QSYXCQ4QRStRzr/1nTQNZaQQArFoABYZbY43ujfsLRfazklrZYe6OUMiQKIM1h4JwV+2ZcwjomNXU2YD8Gue4vnPA8KwsUtjygPdE732/IK/Frz+Hnss63ZXg40nnG/PqbiDVMSnyuXmJAV1uq9UyC8QLx959HtQZBBR1NNwEKK0VG/RtmJaM8FrvocsBubmA+oLAdJ4F7B5DwfaKjwwSV8Ye5r4Tw==" Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10520, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10533, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+01:00" level=debug msg="new websocket client" Jul 18 20:54:19 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket established Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10540, instance 1, run: true Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+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 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10547, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10554, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10561, instance 1, run: true Jul 18 20:54:19 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:19+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10568, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10575, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:19 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10588, instance 1, run: true Jul 18 20:54:19 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10595, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10602, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10609, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10616, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10623, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10630, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:20 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54:20+01:00" level=debug msg="completed challenge" Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10644, instance 1, run: true Jul 18 20:54:20 richdacvolumio go-librespot[10456]: time="2025-07-18T20:54: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" Jul 18 20:54:20 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:20 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10654, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket closed Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10661, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10668, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10675, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10682, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10689, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10700, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10709, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10716, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10723, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10730, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10737, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10744, instance 1, run: true Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:20 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10756, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10764, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10771, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10778, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10785, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10792, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10799, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10810, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10819, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10826, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10833, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10840, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10847, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10854, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:21 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10864, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10874, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L pi-star.local. Jul 18 20:54:21 richdacvolumio volumio[1018]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L nas-BB-F4-D3.local. Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10885, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10892, instance 1, run: true Jul 18 20:54:21 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10900, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10909, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10922, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10929, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10936, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10943, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10950, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10957, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10964, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10977, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10984, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10991, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 10998, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: Getting Spotify volume Jul 18 20:54:22 richdacvolumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:22 richdacvolumio volumio[1018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 18 20:54:22 richdacvolumio volumio[1018]: (node:1018) 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: 63) Jul 18 20:54:22 richdacvolumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11005, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11012, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11019, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:22 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11032, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11039, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11046, instance 1, run: true Jul 18 20:54:22 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11053, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11060, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11067, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11074, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11086, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11094, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11101, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11108, instance 1, run: true Jul 18 20:54:23 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:23 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 198. Jul 18 20:54:23 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11115, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:23 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:23 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:23 richdacvolumio go-librespot[11116]: Librespot-go daemon starting... Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11123, instance 1, run: true Jul 18 20:54:23 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:23+01:00" level=info msg="generated new device id: 55672246c7b5e0f4e744948c0c959ffa2a39a890" Jul 18 20:54:23 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:23+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11137, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11150, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11157, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11166, instance 1, run: true Jul 18 20:54:23 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:23+01:00" level=debug msg="obtained new client token: AAAJwRgjL1Y5nnbGKnwjFrceVUcCsC9YYuif6nxJGKTiC0nPPitzIyN2qNaM4eutCB/5w9jwr7wJ1kENKOzfIPzYbv8HxwZe9KM58/oCKN34tYsO8LFeCFxbyhzo9xea0PN9JFb9As1DXjU27tjy4+vfNUKzIqhcFFUGILIyUqSQWk3aJfSDn8PWJRLpFnmk2hIWPJd3kajwJ8dvg5FYws149o3aGRzAWtZQ7F1DLyNoi7D1dtrX775wST8Xvg==" Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11173, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11180, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11187, instance 1, run: true Jul 18 20:54:23 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:23+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 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11194, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:23 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11207, instance 1, run: true Jul 18 20:54:23 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11214, instance 1, run: true Jul 18 20:54:23 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:23+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11221, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11228, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11235, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11242, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11249, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11262, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11269, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11276, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11283, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11290, instance 1, run: true Jul 18 20:54:24 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54:24+01:00" level=debug msg="completed challenge" Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11297, instance 1, run: true Jul 18 20:54:24 richdacvolumio go-librespot[11116]: time="2025-07-18T20:54: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" Jul 18 20:54:24 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:24 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11304, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11317, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11324, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11331, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11338, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11345, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11352, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11359, instance 1, run: true Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:24 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:24 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11372, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11379, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11386, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11393, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11400, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11407, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11414, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11427, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11434, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11441, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11448, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11455, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11462, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11469, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11482, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11489, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11496, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11503, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11510, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11517, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11524, instance 1, run: true Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:25 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11537, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11544, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11551, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11558, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11565, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11572, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11579, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11592, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:26 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11599, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11606, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11613, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11620, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11627, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11634, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:26 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11647, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11654, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11661, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11668, instance 1, run: true Jul 18 20:54:26 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11675, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11682, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11689, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11703, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11710, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11717, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11724, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11731, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11738, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11745, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11757, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11769, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11786, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11793, instance 1, run: true Jul 18 20:54:27 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:27 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 199. Jul 18 20:54:27 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11801, instance 1, run: true Jul 18 20:54:27 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:27 richdacvolumio go-librespot[11800]: Librespot-go daemon starting... Jul 18 20:54:27 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:27+01:00" level=info msg="generated new device id: 62da68d616dfb036cf1d6226bd22f29afd0bd7b3" Jul 18 20:54:27 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:27+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11809, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11822, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:27 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11835, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11843, instance 1, run: true Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11850, instance 1, run: true Jul 18 20:54:27 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:27+01:00" level=debug msg="obtained new client token: AACGWG0UlEvh66m/G7j2QN3Mduz8J99J/okeBsapuC/VmUaZSGWad9LLEq+u0/CSIbph5U0GnMpzX5WdrLdtJYmaeR1e+ojwxXsw2cfG7Y24phfXebNXFPNd0SI6PL01u6474RQo1DOo8719bjz6qeDcrVUfnwXp1oQgW5c99c0L+YuqpNwtYZZvyA3VrvoSDFBd5fL6Er8DT8l1ZZYMjw7ei5dLqKOplVA7I0xN/1e3K0tvqLstTOF8WAf8XA==" Jul 18 20:54:27 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11857, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11864, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11871, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:28 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:28+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 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11878, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11891, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11898, instance 1, run: true Jul 18 20:54:28 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:28+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11905, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11912, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11919, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11926, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11933, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11946, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11953, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11960, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11967, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11974, instance 1, run: true Jul 18 20:54:28 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:28+01:00" level=debug msg="completed challenge" Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11981, instance 1, run: true Jul 18 20:54:28 richdacvolumio go-librespot[11800]: time="2025-07-18T20:54:28+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 18 20:54:28 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:28 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 11988, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:28 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12000, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12008, instance 1, run: true Jul 18 20:54:28 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12015, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12022, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12029, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12036, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12043, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12053, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12063, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12070, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12077, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:29 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12084, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12091, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12098, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12109, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12118, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12125, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12132, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12139, instance 1, run: true Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:29 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12150, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12159, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12166, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12173, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12180, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12187, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12194, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12207, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12214, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12221, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12228, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12235, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12242, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12249, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:30 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12262, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12269, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12276, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12283, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12290, instance 1, run: true Jul 18 20:54:30 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12297, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12304, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12317, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12324, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12331, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12338, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12345, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12352, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12359, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12372, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12379, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12386, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12393, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12400, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12407, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12414, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:31 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12427, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12434, instance 1, run: true Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12441, instance 1, run: true Jul 18 20:54:31 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:31 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 200. Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12449, instance 1, run: true Jul 18 20:54:31 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:31 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:31 richdacvolumio go-librespot[12448]: Librespot-go daemon starting... Jul 18 20:54:31 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12456, instance 1, run: true Jul 18 20:54:31 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:31+01:00" level=info msg="generated new device id: b17b26e6409976aa339b2de46a4ec37af337f499" Jul 18 20:54:31 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:31+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12469, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12476, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12488, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12497, instance 1, run: true Jul 18 20:54:32 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:32+01:00" level=debug msg="obtained new client token: AAB+BrSMEtIHR6zgaBnYykhwqtoXIK7LSEzymZZt3AONDD3Y257TFPtXNkim17X2OgnmLXNFlgA5kfHuEinFxXIfABVoVHIyi97HYYcf9zP8YihDqf44pBmgK1+i9qA0bjMkD2D6xeLXoUd8LhBdVPkqVr/8GCb2UQ6fWrWji7hreWqA0SJGhR39oiXmZ2l9CsCNUBTYox34FC/V0D6N8HRdZdmXrCeRAuDk7TzLp/D/BdLF6a9p5cgu2sU=" Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12504, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12511, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12518, instance 1, run: true Jul 18 20:54:32 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:32+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 18 20:54:32 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:32 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:32+01:00" level=debug msg="new websocket client" Jul 18 20:54:32 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket established Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12525, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12538, instance 1, run: true Jul 18 20:54:32 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:32+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12545, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12552, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12559, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12566, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12592, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12620, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:32 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12635, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12642, instance 1, run: true Jul 18 20:54:32 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12649, instance 1, run: true Jul 18 20:54:33 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:33+01:00" level=debug msg="completed challenge" Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12656, instance 1, run: true Jul 18 20:54:33 richdacvolumio go-librespot[12448]: time="2025-07-18T20:54:33+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 18 20:54:33 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:33 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:33 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket closed Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12663, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12670, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12677, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12689, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12697, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12704, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12711, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12718, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12725, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12732, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12744, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12752, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12759, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12766, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12773, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12780, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12787, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:33 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12800, instance 1, run: true Jul 18 20:54:33 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12807, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12814, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12821, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12828, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12835, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12842, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12854, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12862, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12869, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12876, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12883, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12890, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12897, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12909, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12917, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12924, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12931, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12938, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12948, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12955, instance 1, run: true Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:34 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:34 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12968, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12975, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12982, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12989, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 12996, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13003, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13010, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13023, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13031, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: Getting Spotify volume Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13038, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:35 richdacvolumio volumio[1018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 18 20:54:35 richdacvolumio volumio[1018]: (node:1018) 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: 64) Jul 18 20:54:35 richdacvolumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13045, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13052, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13059, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13066, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:35 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13079, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13086, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13093, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13100, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13107, instance 1, run: true Jul 18 20:54:35 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13114, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13121, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13134, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:36 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13141, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 20:54:36 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:36 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 201. Jul 18 20:54:36 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13149, instance 1, run: true Jul 18 20:54:36 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:36 richdacvolumio go-librespot[13148]: Librespot-go daemon starting... Jul 18 20:54:36 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:36+01:00" level=info msg="generated new device id: eb2008379e2bdf7b867dec0f12df1e18e11fd005" Jul 18 20:54:36 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:36+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13157, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13169, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13176, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13183, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13195, instance 1, run: true Jul 18 20:54:36 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:36+01:00" level=debug msg="obtained new client token: AADmoA5KdKPqg/xed7jAILRODpBLpj7vIS4bvX+NzZncTT58CzHYeNLOOepNUL+aWBapWpL6UHxofu95nBhYk3Kzkr6JT7OILBuczPOeb0OEopL5xoCZL4RuEnBFCIfgxolpH2un1e2DT98vVmhoX8k+M4JMe1LUaOyPVGSqsb2a2CIdqfd1mkFMbrFbiEsulu1dT6Dltnre5gsXygfP/uPjV9KYVmzCbZY045HwP3j2AeLARwGnNUWSL1D8sw==" Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13205, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13212, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13219, instance 1, run: true Jul 18 20:54:36 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:36+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 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13226, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13233, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13240, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:36 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13252, instance 1, run: true Jul 18 20:54:36 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:36+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13260, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13267, instance 1, run: true Jul 18 20:54:36 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13274, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13281, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13288, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13295, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13308, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13315, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13322, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13329, instance 1, run: true Jul 18 20:54:37 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:37+01:00" level=debug msg="completed challenge" Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13336, instance 1, run: true Jul 18 20:54:37 richdacvolumio go-librespot[13148]: time="2025-07-18T20:54:37+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 18 20:54:37 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:37 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13343, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13350, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13363, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13378, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13391, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13398, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13405, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13412, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13419, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:37 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13431, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13439, instance 1, run: true Jul 18 20:54:37 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13446, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13453, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13460, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13467, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13474, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13487, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13494, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13501, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13508, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13515, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13522, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13529, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13542, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13549, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13556, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13563, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13570, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13577, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13584, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:38 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13597, instance 1, run: true Jul 18 20:54:38 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13604, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13611, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13618, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:39 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13625, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13632, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13639, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13652, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13659, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13666, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13673, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13680, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13687, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13694, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13707, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13714, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13721, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13728, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13735, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13742, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13749, instance 1, run: true Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:39 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13762, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13769, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13776, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13783, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13790, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13797, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13804, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13816, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13824, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13831, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13838, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13845, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13852, instance 1, run: true Jul 18 20:54:40 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:40 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 202. Jul 18 20:54:40 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13860, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:40 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:40 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:40 richdacvolumio go-librespot[13858]: Librespot-go daemon starting... Jul 18 20:54:40 richdacvolumio go-librespot[13858]: time="2025-07-18T20:54:40+01:00" level=info msg="generated new device id: 6ec162a03e5e5aa29669d332744460fd58313b98" Jul 18 20:54:40 richdacvolumio go-librespot[13858]: time="2025-07-18T20:54:40+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13878, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13885, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13892, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13899, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13908, instance 1, run: true Jul 18 20:54:40 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13915, instance 1, run: true Jul 18 20:54:41 richdacvolumio go-librespot[13858]: time="2025-07-18T20:54:41+01:00" level=debug msg="obtained new client token: AADTQ/3w5oS66MKOZTWkNHoTLCmbe//Z3oGfk3cz2HlGCzNCUjSIYHhuI9uFsBxGjJT2vdZX+zY/up3XM7RWP66H426q/XfBzZ65HANkfeW7cpfO9LJKTumnHmuCy8Ikw1hlwXigiXdcmCFpzvFRPr9p2gzGw/BcppPsZqQOZk4WUWcY5127TcisbAMOXXhXZTVv3noqc7g2Gy3N9n+CLzXgAE4HmRkvwETac3vX3HChcn8Pt0QoaCKqr3mJAQ==" Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13922, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13935, instance 1, run: true Jul 18 20:54:41 richdacvolumio go-librespot[13858]: time="2025-07-18T20:54: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 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13942, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13949, instance 1, run: true Jul 18 20:54:41 richdacvolumio go-librespot[13858]: time="2025-07-18T20:54:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Jul 18 20:54:41 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:41 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13956, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13963, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13970, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13977, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13989, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 13997, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14004, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14011, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14018, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14025, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14032, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:41 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14044, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14052, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14059, instance 1, run: true Jul 18 20:54:41 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14066, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14073, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14080, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:42 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14087, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14100, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14107, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14114, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14121, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14128, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14135, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14142, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14155, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14162, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14169, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14176, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14183, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14190, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14197, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:42 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14210, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14217, instance 1, run: true Jul 18 20:54:42 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14224, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14231, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14238, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14245, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14252, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14265, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14272, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14279, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14287, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14294, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14301, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14308, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14321, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14328, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14335, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14342, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14349, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14356, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14363, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:43 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14376, instance 1, run: true Jul 18 20:54:43 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14383, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14390, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14397, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14404, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14411, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14418, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14431, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14438, instance 1, run: true Jul 18 20:54:44 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:44 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 203. Jul 18 20:54:44 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14446, instance 1, run: true Jul 18 20:54:44 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:44 richdacvolumio go-librespot[14445]: Librespot-go daemon starting... Jul 18 20:54:44 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:44+01:00" level=info msg="generated new device id: cb6d92b8a9cd609b80da6fe7f5f961b527eaf542" Jul 18 20:54:44 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:44+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14457, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14466, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14473, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14480, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14493, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14501, instance 1, run: true Jul 18 20:54:44 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:44+01:00" level=debug msg="obtained new client token: AACQOMjs/HTSYMUVGyyBumwr6l5Xb3pjSoNvmhvGX9B7y/CRCkMm8cZcQ3m7bLeibKoAEyo7R2LpLfP3WEe9vZQrD+0UtFfpkEacZvQrue6zakAFGB8ITcXcCXgsazK/x/ddbzd2sUF+hTVJ8VYsK7nn1p0RKn9UBg9sJ9t+v+4QE+yw1KpIwSUnWxQAffI/Dcnx+u08CfFUbaP8Z1/We9d/MG18DmE17JXEGz6bMq0U/wUo23Wyiu9955DUng==" Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14508, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14515, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14522, instance 1, run: true Jul 18 20:54:44 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14529, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:44 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14536, instance 1, run: true Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:44 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14549, instance 1, run: true Jul 18 20:54:45 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:45+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14556, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 18 20:54:45 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:45 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:45+01:00" level=debug msg="new websocket client" Jul 18 20:54:45 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket established Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14563, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: Preload queue cleared Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14570, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14577, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14584, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14591, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14604, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14611, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14619, instance 1, run: true Jul 18 20:54:45 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54:45+01:00" level=debug msg="completed challenge" Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14626, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14633, instance 1, run: true Jul 18 20:54:45 richdacvolumio go-librespot[14445]: time="2025-07-18T20:54: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 18 20:54:45 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:45 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:45 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket closed Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14640, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14647, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:45 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14660, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14667, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14674, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14681, instance 1, run: true Jul 18 20:54:45 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14688, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14696, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14703, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14716, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14723, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14730, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14738, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14745, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14752, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14759, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14771, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14779, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14786, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14793, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14800, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14807, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14820, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14827, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14834, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14841, instance 1, run: true Jul 18 20:54:46 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14848, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14855, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14862, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14869, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14882, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14889, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: Preload queue cleared Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14896, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14903, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14910, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14917, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14941, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14951, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14958, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14965, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14972, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14979, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14986, instance 1, run: true Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:47 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:47 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 14996, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15006, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15013, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15020, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: Getting Spotify volume Jul 18 20:54:48 richdacvolumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:48 richdacvolumio volumio[1018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 18 20:54:48 richdacvolumio volumio[1018]: (node:1018) 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: 65) Jul 18 20:54:48 richdacvolumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15027, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15034, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15041, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15053, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15061, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15068, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15075, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15082, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15089, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15096, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:48 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:48 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 204. Jul 18 20:54:48 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:48 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15109, instance 1, run: true Jul 18 20:54:48 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:48 richdacvolumio go-librespot[15099]: Librespot-go daemon starting... Jul 18 20:54:48 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54:48+01:00" level=info msg="generated new device id: a6d6da01c40992aa16dca173e12592f4365d6958" Jul 18 20:54:48 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54:48+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15123, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15130, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15137, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15144, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15151, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15159, instance 1, run: true Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:48 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:49 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54:49+01:00" level=debug msg="obtained new client token: AABBgw3P/i6p2o4m9oeMo1Q34SITID8jhKQyYfCe6LVxMjqoyqm5kqxaq5yAKJ+DLGTpB/XzoxFA0MeHa47Hc0DC+xL2mQnkxE0aXuaca8wc+oZe83pbVsFxJqAA6qMyfHERDWNXZOdvx3Oj3nHBiNKDEFhzG+UmtKeq4+zOuGQjv7CEeuJRaB5tjZS5UAuTaTIhdJPvn2YjoGJNlTsQmtN0Tg00D1U3xlA/qS2Fc6yfh9hp1rqGcbE0nPUkaQ==" Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15172, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15179, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15186, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15193, instance 1, run: true Jul 18 20:54:49 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15200, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15207, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15214, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15227, instance 1, run: true Jul 18 20:54:49 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54:49+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15234, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15241, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15248, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15255, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15262, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15269, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:49 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15282, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15289, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15296, instance 1, run: true Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15303, instance 1, run: true Jul 18 20:54:49 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54:49+01:00" level=debug msg="completed challenge" Jul 18 20:54:49 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15310, instance 1, run: true Jul 18 20:54:49 richdacvolumio go-librespot[15099]: time="2025-07-18T20:54: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 18 20:54:49 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:49 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15317, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15324, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15337, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15344, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15351, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15358, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15365, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15372, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15379, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15392, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15399, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15406, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15413, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15420, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15427, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15434, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:50 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15447, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15454, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15461, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15468, instance 1, run: true Jul 18 20:54:50 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15475, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15482, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15489, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15502, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15509, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15516, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15523, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15530, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15537, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15544, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15556, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15564, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15571, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:51 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15578, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15585, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15592, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15599, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:51 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15611, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15620, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15627, instance 1, run: true Jul 18 20:54:51 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15634, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15641, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15648, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15655, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15668, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15676, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15683, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15690, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15697, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15704, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15711, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15724, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15732, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15739, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15746, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15753, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: Preload queue cleared Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15760, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15767, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:52 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15778, instance 1, run: true Jul 18 20:54:52 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15787, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15794, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15801, instance 1, run: true Jul 18 20:54:53 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:53 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 205. Jul 18 20:54:53 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15809, instance 1, run: true Jul 18 20:54:53 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:53 richdacvolumio go-librespot[15808]: Librespot-go daemon starting... Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15816, instance 1, run: true Jul 18 20:54:53 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54:53+01:00" level=info msg="generated new device id: 265964f502c92484756f2fa484e11ca4dfb83d08" Jul 18 20:54:53 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54:53+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15829, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15842, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15849, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15856, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15865, instance 1, run: true Jul 18 20:54:53 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54:53+01:00" level=debug msg="obtained new client token: AAAvITDSmU7FpKfRUM7TrAT8SZN4peRuReVl0h0r5sw1tmH8YBl19JKO5v9fWllYds8NraS+mGNqYqqHtXuXO2sn7hNDUPvRSnrGRxsuigGdgXu3DnQDLBMMB6VQC113HKHBJZRJ1hbUpGAHqvq9VA1yuqrjcLwoEfcozoC0VrvaaWC76//IF3KJHyPcJ1pOArxLBiqtnxdI7nZhHL0LeR+tTBrOIHAhYNXPKmFSiPpba29kGCcJAbbnvnHR3A==" Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15872, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15879, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15886, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:53 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15899, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15906, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15913, instance 1, run: true Jul 18 20:54:53 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54:53+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15920, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15927, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15934, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15941, instance 1, run: true Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:53 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15953, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15961, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15968, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15975, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15982, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15989, instance 1, run: true Jul 18 20:54:54 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54:54+01:00" level=debug msg="completed challenge" Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 15996, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:54 richdacvolumio go-librespot[15808]: time="2025-07-18T20:54: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" Jul 18 20:54:54 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:54 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16009, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16016, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16023, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16030, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16037, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16044, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:54 richdacvolumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16051, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:54 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16064, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16071, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16078, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16088, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16095, instance 1, run: true Jul 18 20:54:54 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16102, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16109, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16122, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16129, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16136, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16143, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16150, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16157, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16164, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16177, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16184, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16191, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16198, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16205, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16212, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16219, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:55 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16231, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16239, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16246, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16253, instance 1, run: true Jul 18 20:54:55 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16260, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16267, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16274, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16287, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16294, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16301, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16308, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16315, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16322, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16329, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16341, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16349, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16356, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16363, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16370, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16377, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16384, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:56 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16396, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16404, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16411, instance 1, run: true Jul 18 20:54:56 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16418, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16425, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16432, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16439, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16451, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16459, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16466, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16473, instance 1, run: true Jul 18 20:54:57 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 18 20:54:57 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 206. Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16480, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16488, instance 1, run: true Jul 18 20:54:57 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Jul 18 20:54:57 richdacvolumio systemd[1]: Started go-librespot Daemon. Jul 18 20:54:57 richdacvolumio go-librespot[16481]: Librespot-go daemon starting... Jul 18 20:54:57 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:57+01:00" level=info msg="generated new device id: 7e3b6f00537df921da9292e9291782b638cb42c5" Jul 18 20:54:57 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:57+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16501, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16513, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16521, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: Initializing connection to go-librespot Websocket Jul 18 20:54:57 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:57+01:00" level=debug msg="new websocket client" Jul 18 20:54:57 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket established Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16543, instance 1, run: true Jul 18 20:54:57 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:57+01:00" level=debug msg="obtained new client token: AADyNs6kxGxgX2Ge/zetnpPqqm76Ift1u2sGVadgQ/8LcmKZryz7e5fgtsyonjCEPCgR7e10gqrML5CbB9nrHe9xOMqVXXuiY4Asf2JP26opeEM6kLP7vcXxq946JKbXW3StHWAJ1/xoExf8SAmZYQ8tda56Wo1tKNFr6gDqcoMw3/KRPtPtYXGve7dqh4tZ/swA0AOcVvIQplkPG4OFZQ7kp/37xY1wBjLfs9TCqPjeK3fn6IyFCIxxBS53lA==" Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16550, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16557, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16564, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:57 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16574, instance 1, run: true Jul 18 20:54:57 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54: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 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16584, instance 1, run: true Jul 18 20:54:57 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16591, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16598, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16605, instance 1, run: true Jul 18 20:54:58 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:58+01:00" level=debug msg="completed keyexchange" Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16612, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16619, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16629, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16639, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16646, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16653, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16660, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16667, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16674, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16684, instance 1, run: true Jul 18 20:54:58 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:58+01:00" level=debug msg="completed challenge" Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16694, instance 1, run: true Jul 18 20:54:58 richdacvolumio go-librespot[16481]: time="2025-07-18T20:54:58+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 18 20:54:58 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 18 20:54:58 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 18 20:54:58 richdacvolumio volumio[1018]: info: Connection to go-librespot Websocket closed Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16701, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16708, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16715, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16722, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16729, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:58 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16739, instance 1, run: true Jul 18 20:54:58 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16749, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16756, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16763, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16770, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16777, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16784, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16795, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16804, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16811, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16818, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16825, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16832, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16839, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16851, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16859, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16866, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16873, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16880, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16887, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16894, instance 1, run: true Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:54:59 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16907, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16914, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16921, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16928, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16935, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16942, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16949, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16962, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16969, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16976, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16983, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16990, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 16997, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: Getting Spotify volume Jul 18 20:55:00 richdacvolumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 18 20:55:00 richdacvolumio volumio[1018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jul 18 20:55:00 richdacvolumio volumio[1018]: (node:1018) 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: 66) Jul 18 20:55:00 richdacvolumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 17004, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jul 18 20:55:00 richdacvolumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 17014, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 17024, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: info: camilladsp spawned new process with pid 17031, instance 1, run: true Jul 18 20:55:00 richdacvolumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 20:55:00 richdacvolumio volumio[1018]: TypeError: Cannot read property 'length' of undefined Jul 18 20:55:00 richdacvolumio volumio[1018]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 18 20:55:00 richdacvolumio volumio[1018]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 18 20:55:00 richdacvolumio volumio[1018]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 18 20:55:00 richdacvolumio volumio[1018]: at Parser.emit (events.js:315:20) Jul 18 20:55:00 richdacvolumio volumio[1018]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 18 20:55:00 richdacvolumio volumio[1018]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 18 20:55:00 richdacvolumio volumio[1018]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 18 20:55:00 richdacvolumio volumio[1018]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 18 20:55:00 richdacvolumio volumio[1018]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 18 20:55:00 richdacvolumio volumio[1018]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 18 20:55:00 richdacvolumio volumio[1018]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 18 20:55:00 richdacvolumio volumio[1018]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 18 20:55:00 richdacvolumio volumio[1018]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 18 20:55:00 richdacvolumio volumio[1018]: at IncomingMessage.emit (events.js:327:22) Jul 18 20:55:00 richdacvolumio volumio[1018]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 18 20:55:00 richdacvolumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 18 20:55:00 richdacvolumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 20:55:01 richdacvolumio sudo[17047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 20:54 Jul 18 20:55:01 richdacvolumio sudo[17047]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"