Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:07 volumiobo go-librespot[10081]: time="2026-02-24T21:05:07+01:00" level=debug msg="fetched chunk 10/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:09 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:10 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:12 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo go-librespot[10081]: time="2026-02-24T21:05:20+01:00" level=debug msg="fetched chunk 11/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo ntpd[1055]: PROTO: 5.1.73.50 unlink local addr 192.168.1.14 -> Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo go-librespot[10081]: time="2026-02-24T21:05:28+01:00" level=trace msg="sent dealer ping" Feb 24 21:05:28 volumiobo go-librespot[10081]: time="2026-02-24T21:05:28+01:00" level=trace msg="received dealer pong" Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo go-librespot[10081]: time="2026-02-24T21:05:31+01:00" level=debug msg="fetched chunk 12/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:37 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:38 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:39 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:40 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:41 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:42 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo go-librespot[10081]: time="2026-02-24T21:05:43+01:00" level=debug msg="fetched chunk 13/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:43 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:44 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:45 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:46 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:46 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:46 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:46 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:47 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:48 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:49 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:50 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:51 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:52 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: verbose: New Socket.io Connection to iual75kpqcvxkdrmsuh3mmdyjdm2-d8d9de3adc5c1a343c5845d905f307fd.eu6.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 21:05:53 volumiobo volumio[9855]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 24 21:05:53 volumiobo volumio[9855]: info: Received Get System Info Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:05:53 volumiobo volumio[9855]: info: Discovery: Getting this device information Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: Listing playlists Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:53 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 21:05:54 volumiobo volumio[9855]: info: Received Get System Info Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:05:54 volumiobo volumio[9855]: info: Discovery: Getting this device information Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:54 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo go-librespot[10081]: time="2026-02-24T21:05:55+01:00" level=debug msg="fetched chunk 14/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:55 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:56 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:57 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo go-librespot[10081]: time="2026-02-24T21:05:58+01:00" level=trace msg="sent dealer ping" Feb 24 21:05:58 volumiobo go-librespot[10081]: time="2026-02-24T21:05:58+01:00" level=trace msg="received dealer pong" Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:58 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:59 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:59 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:59 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:59 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:05:59 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:00 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:01 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:02 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:03 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:04 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:05 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 21:06:06 volumiobo volumio[9855]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 24 21:06:06 volumiobo volumio[9855]: info: Received Get System Version Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 21:06:06 volumiobo volumio[9855]: info: Received Get System Info Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:06:06 volumiobo volumio[9855]: info: Discovery: Getting this device information Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:06 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo go-librespot[10081]: time="2026-02-24T21:06:07+01:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:5ULRlgiSPSKTaJN1vU9yso" Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:07 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 24 21:06:08 volumiobo volumio[9855]: info: Preparing to save Alsa Options, stopping services first Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPause Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreStateMachine::pause Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreStateMachine::stPlaybackTimer Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreStateMachine::servicePause Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::servicePause Feb 24 21:06:08 volumiobo volumio[9855]: info: Spotify Received pause Feb 24 21:06:08 volumiobo volumio[9855]: SPOTIFY: SPOTIFY PAUSE Feb 24 21:06:08 volumiobo volumio[9855]: SPOTIFY: {"status":"play","title":"Shape of You - Acoustic","artist":"Ed Sheeran","album":"Shape of You (Acoustic)","albumart":"https://i.scdn.co/image/ab67616d00001e02c1c4353f16efbdfde1e819f2","uri":"spotify:track:5ULRlgiSPSKTaJN1vU9yso","trackType":"spotify","codec":"ogg","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":81,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Feb 24 21:06:08 volumiobo volumio[9855]: info: Sending Spotify command to local API: /player/pause Feb 24 21:06:08 volumiobo volumio[9855]: info: Saving Audio Output to: {"output_device":{"value":"4","label":"snd_rpi_hifiberry_digi"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:08 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:10 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:11 volumiobo volumio[9855]: info: Device 4 does not have any Mixer Control Available Feb 24 21:06:11 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:11 volumiobo vtcs[10646]: [2026-02-24 21:06:11.139] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 24 21:06:11 volumiobo vtcs[10646]: [2026-02-24 21:06:11.140] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 24 21:06:11 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:11 volumiobo sudo[14079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:11 volumiobo sudo[14079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 21:06:11 volumiobo volumio[9855]: info: Updating Volume Controller Parameters: Device: 4 Name: snd_rpi_hifiberry_digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 24 21:06:11 volumiobo volumio[9855]: info: Disabling external Volume Control Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:06:11 volumiobo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:11 volumiobo systemd[1]: vtcs.service: Deactivated successfully. Feb 24 21:06:11 volumiobo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 24 21:06:11 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:11 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:11 volumiobo sudo[14079]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:11 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:11.212+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PLAYING positionMs=144000 volume=100 Feb 24 21:06:11 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:11.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:11 volumiobo volumio[9855]: info: Preparing to generate the ALSA configuration file Feb 24 21:06:11 volumiobo sudo[14087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:11 volumiobo sudo[14087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:11 volumiobo volumio[9855]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 21:06:11 volumiobo volumio[9855]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 24 21:06:11 volumiobo volumio[9855]: info: Reading ALSA contributions from plugins. Feb 24 21:06:11 volumiobo sudo[14094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:11 volumiobo sudo[14094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:11 volumiobo sudo[14087]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:11 volumiobo go-librespot[10081]: time="2026-02-24T21:06:11+01:00" level=debug msg="pause track at 147250ms" Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:11 volumiobo sudo[14094]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:11 volumiobo sudo[14098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:11 volumiobo sudo[14098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:11 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:11 volumiobo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 24 21:06:11 volumiobo qobuz-connect[10527]: 20260224 21:06:11.363 [10527.10527] INFO SampleApp: Stopping Local configuration server Feb 24 21:06:11 volumiobo go-librespot[10081]: time="2026-02-24T21:06:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 21:06:11 volumiobo qobuz-connect[10527]: 20260224 21:06:11.444 [10527.10527] INFO SampleApp: shat down connection on UNIX socket Feb 24 21:06:11 volumiobo systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 24 21:06:11 volumiobo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:11 volumiobo go-librespot[10081]: time="2026-02-24T21:06:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 21:06:11 volumiobo go-librespot[10081]: time="2026-02-24T21:06:11+01:00" level=trace msg="emitting websocket event: paused" Feb 24 21:06:11 volumiobo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:11 volumiobo sudo[14098]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:12 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 21:06:13 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:13 volumiobo volumio[9855]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EVHGWrwldPRtj","uri":"spotify:track:5ULRlgiSPSKTaJN1vU9yso","play_origin":"playlist"}} Feb 24 21:06:13 volumiobo volumio[9855]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 21:06:13 volumiobo volumio[9855]: SPOTIFY: {"status":"pause","service":"spop","title":"Shape of You - Acoustic","artist":"Ed Sheeran","album":"Shape of You (Acoustic)","albumart":"https://i.scdn.co/image/ab67616d00001e02c1c4353f16efbdfde1e819f2","uri":"spotify:track:5ULRlgiSPSKTaJN1vU9yso","trackType":"spotify","seek":146000,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::servicePushState Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:13 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:13 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:13 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:13.473+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:13 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:13.473+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:13 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 21:06:13 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:13 volumiobo volumio[9855]: info: Executing endpoint qc_getconfig Feb 24 21:06:13 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.529 [14106.14106] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.535 [14106.14106] INFO VolumeManager: [0x8a4258]: Setting new playback volume: 75 Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.535 [14106.14106] INFO VolumeManager: [0x8a4258]: Setting new mute state: 0 Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.535 [14106.14106] INFO QobuzConnect: [0x8a4c28]: Client initialized! Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.535 [14106.14106] INFO SampleApp: Starting Avahi advertising, name: volumio_B&O, service name: _qobuz-connect._tcp Feb 24 21:06:13 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 21:06:13 volumiobo volumio[9855]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 21:06:13 volumiobo volumio[9855]: info: Asound.conf file written Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.551 [14106.14106] INFO LocalConfigManager: [0x8a3b38]: Starting Local Configuration server Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.551 [14106.14106] INFO SampleApp: Starting Local configuration server Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.552 [14106.14106] INFO SampleApp: Connected to UNIX socket client 0x88e818 Feb 24 21:06:13 volumiobo sudo[14121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 21:06:13 volumiobo sudo[14121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:13 volumiobo sudo[14121]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:13 volumiobo qobuz-connect[14106]: 20260224 21:06:13.679 [14106.14106] INFO SampleApp: Playback volume changed: 75 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:14 volumiobo volumio[9855]: Found hardware: "RPi-WM8804" "" "" "" "" Feb 24 21:06:14 volumiobo volumio[9855]: Hardware is initialized using a generic method Feb 24 21:06:14 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:14 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:14 volumiobo sudo[14141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:14 volumiobo sudo[14141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:14 volumiobo sudo[14141]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:14 volumiobo sudo[14143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:14 volumiobo sudo[14143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:14 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:14 volumiobo systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Feb 24 21:06:14 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:14 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:14 volumiobo sudo[14153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:14 volumiobo sudo[14153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:14 volumiobo sudo[14155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14155]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:14 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:14 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo sudo[14157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:14 volumiobo sudo[14157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:14 volumiobo sudo[14153]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:14 volumiobo sudo[14169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:14 volumiobo sudo[14169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:14 volumiobo sudo[14171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:14 volumiobo sudo[14173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:14 volumiobo sudo[14182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:14 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:14 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:14 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:14 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:14 volumiobo sudo[14189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:14 volumiobo sudo[14189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo sudo[14182]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo sudo[14195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:14 volumiobo sudo[14195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:14 volumiobo systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Feb 24 21:06:14 volumiobo systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Feb 24 21:06:14 volumiobo systemd[1]: mpd_oled_plugin.service: Consumed 6.278s CPU time. Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:14 volumiobo sudo[14171]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:14 volumiobo sudo[14169]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:15 volumiobo volumio[9855]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:15 volumiobo volumio[9855]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:15 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:15 volumiobo sudo[14173]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:15 volumiobo sudo[14189]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 21:06:15 volumiobo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 24 21:06:15 volumiobo qobuz-connect[14106]: 20260224 21:06:15.042 [14106.14106] INFO SampleApp: Stopping Local configuration server Feb 24 21:06:15 volumiobo sudo[14216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:15 volumiobo sudo[14216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:15 volumiobo sudo[14217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:15 volumiobo sudo[14217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:15 volumiobo systemd[1]: mpd.service: Deactivated successfully. Feb 24 21:06:15 volumiobo systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 21:06:15 volumiobo systemd[1]: mpd.service: Consumed 1.297s CPU time. Feb 24 21:06:15 volumiobo sudo[14220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 21:06:15 volumiobo sudo[14220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:15 volumiobo sudo[14219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:15 volumiobo sudo[14219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:15 volumiobo systemd[1]: mpd.socket: Deactivated successfully. Feb 24 21:06:15 volumiobo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 21:06:15 volumiobo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 21:06:15 volumiobo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 21:06:15 volumiobo systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 21:06:15 volumiobo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::getcurrentVolume Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:15 volumiobo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 24 21:06:15 volumiobo sudo[14220]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 21:06:15 volumiobo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:15 volumiobo systemd[1]: shairport-sync.service: Consumed 2.055s CPU time. Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:15 volumiobo sudo[14216]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo sudo[14217]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.224+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.225+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:15 volumiobo sudo[14226]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:06:15 volumiobo sudo[14226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 21:06:15 volumiobo volumio[9855]: info: Shairport-Sync Started Feb 24 21:06:15 volumiobo volumio[9855]: info: Shairport-Sync Started Feb 24 21:06:15 volumiobo sudo[14226]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo volumio[9855]: info: Executing endpoint tc_getconfig Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 24 21:06:15 volumiobo vtcs[14228]: STARTING TidalConnect services, version: 1.6.1 Feb 24 21:06:15 volumiobo vtcs[14228]: STARTED TidalConnect services. Feb 24 21:06:15 volumiobo volumio[9855]: info: Executing endpoint tc_connect Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 24 21:06:15 volumiobo volumio[9855]: info: Connecting to TidalConnect Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::servicePushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo volumio[9855]: info: CorePlayQueue::getTrack 0 Feb 24 21:06:15 volumiobo volumio[9855]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 24 21:06:15 volumiobo volumio[9855]: verbose: CURRENT POSITION 0 Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::syncState stateService stop Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::syncState currentStatus pause Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::servicePushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo volumio[9855]: info: CorePlayQueue::getTrack 0 Feb 24 21:06:15 volumiobo volumio[9855]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 24 21:06:15 volumiobo volumio[9855]: verbose: CURRENT POSITION 0 Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::syncState stateService stop Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::syncState currentStatus pause Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:15 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.398+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.399+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.401+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.401+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.404+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.405+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.406+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:15 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:15.407+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:15 volumiobo qobuz-connect[14106]: 20260224 21:06:15.554 [14106.14106] INFO SampleApp: shat down connection on UNIX socket Feb 24 21:06:15 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 21:06:15 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:15 volumiobo systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 24 21:06:15 volumiobo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:15 volumiobo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:15 volumiobo sudo[14195]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo sudo[14219]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:15 volumiobo volumio[9855]: info: Executing endpoint qc_getconfig Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.667 [14261.14261] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 21:06:15 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 21:06:15 volumiobo volumio[9855]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.673 [14261.14261] INFO VolumeManager: [0x14a0258]: Setting new playback volume: 75 Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.673 [14261.14261] INFO VolumeManager: [0x14a0258]: Setting new mute state: 0 Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.673 [14261.14261] INFO QobuzConnect: [0x14a0c28]: Client initialized! Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.674 [14261.14261] INFO SampleApp: Starting Avahi advertising, name: volumio_B&O, service name: _qobuz-connect._tcp Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.691 [14261.14261] INFO LocalConfigManager: [0x149fb38]: Starting Local Configuration server Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.692 [14261.14261] INFO SampleApp: Starting Local configuration server Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.692 [14261.14261] INFO SampleApp: Connected to UNIX socket client 0x148a818 Feb 24 21:06:15 volumiobo qobuz-connect[14261]: 20260224 21:06:15.818 [14261.14261] INFO SampleApp: Playback volume changed: 75 Feb 24 21:06:15 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:16 volumiobo mpd[14244]: 2026-02-24T21:06:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 21:06:16 volumiobo systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 21:06:16 volumiobo sudo[14157]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:16 volumiobo sudo[14143]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:16 volumiobo volumio[9855]: error: MPD error: The expression evaluated to a falsy value: Feb 24 21:06:16 volumiobo volumio[9855]: assert.ok(self.idling) Feb 24 21:06:16 volumiobo volumio[9855]: error: The expression evaluated to a falsy value: Feb 24 21:06:16 volumiobo volumio[9855]: assert.ok(self.idling) Feb 24 21:06:16 volumiobo volumio[9855]: error: updateQueue error: null Feb 24 21:06:16 volumiobo systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Feb 24 21:06:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:16 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:17 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:17 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:18 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:18 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:18 volumiobo volumio[9855]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:18 volumiobo volumio[9855]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:18 volumiobo volumio[9855]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:18 volumiobo volumio[9855]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:18 volumiobo sudo[14309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 21:06:18 volumiobo sudo[14309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:18 volumiobo sudo[14316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 21:06:18 volumiobo sudo[14316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:18 volumiobo sudo[14309]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:18 volumiobo sudo[14316]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:18 volumiobo volumio[9855]: info: TidalConnect service started! Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:18 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:19 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:20 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: TidalConnect service started! Feb 24 21:06:21 volumiobo volumio[9855]: info: TidalConnect service started! Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:21 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:22 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:23 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:24 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:25 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:26 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:27 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:28 volumiobo go-librespot[10081]: time="2026-02-24T21:06:28+01:00" level=trace msg="sent dealer ping" Feb 24 21:06:28 volumiobo go-librespot[10081]: time="2026-02-24T21:06:28+01:00" level=trace msg="received dealer pong" Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:29 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:30 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:31 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:32 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 24 21:06:33 volumiobo volumio[9855]: info: Preparing to save Alsa Options, stopping services first Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPause Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreStateMachine::pause Feb 24 21:06:33 volumiobo volumio[9855]: info: Saving Audio Output to: {"output_device":{"value":"4","label":"snd_rpi_hifiberry_digi"},"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"}} Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: Enabling I2S DAC: Allo DigiOne Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo sudo[14607]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 24 21:06:33 volumiobo sudo[14607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo sudo[14607]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo volumio[9855]: info: No Overlays Loaded Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo sudo[14610]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Feb 24 21:06:33 volumiobo sudo[14610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo volumio[9855]: warn: Unable to locate the device snd_rpi_hifiberry_digi a reboot may be required. Feb 24 21:06:33 volumiobo sudo[14610]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/#sound-dai-cells Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/compatible Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/reg Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/PVDD-supply Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/DVDD-supply Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/status Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/wlf,reset-gpio Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Feb 24 21:06:33 volumiobo kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: Device 2 does not have any Mixer Control Available Feb 24 21:06:33 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo vtcs[14228]: [2026-02-24 21:06:33.496] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 24 21:06:33 volumiobo vtcs[14228]: [2026-02-24 21:06:33.497] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=5 errorno=104 error=Connection reset by peer Feb 24 21:06:33 volumiobo vtcs[14228]: [2026-02-24 21:06:33.497] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 21:06:33 volumiobo volumio[9855]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 24 21:06:33 volumiobo volumio[9855]: info: Disabling external Volume Control Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 21:06:33 volumiobo sudo[14626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:33 volumiobo sudo[14626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreStateMachine::pushState Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioPushState Feb 24 21:06:33 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output update for this device Feb 24 21:06:33 volumiobo volumio[9855]: info: MRS: Pushing multiroomSync output Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:33.544+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" state=STATUS_PAUSED positionMs=146000 volume=100 Feb 24 21:06:33 volumiobo volumio5-onboarding[1621]: time=2026-02-24T21:06:33.545+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:36200 @ 0x2c00c90" id=spotify:track:5ULRlgiSPSKTaJN1vU9yso title="Shape of You - Acoustic" Feb 24 21:06:33 volumiobo volumio[9855]: info: Preparing to generate the ALSA configuration file Feb 24 21:06:33 volumiobo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 24 21:06:33 volumiobo systemd[1]: vtcs.service: Deactivated successfully. Feb 24 21:06:33 volumiobo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 24 21:06:33 volumiobo sudo[14626]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo sudo[14633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:33 volumiobo sudo[14633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo volumio[9855]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 21:06:33 volumiobo volumio[9855]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 24 21:06:33 volumiobo volumio[9855]: info: Reading ALSA contributions from plugins. Feb 24 21:06:33 volumiobo sudo[14639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:33 volumiobo sudo[14639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo volumio[9855]: info: I2S Param [object Object] successfully enabled Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo sudo[14633]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo sudo[14639]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 21:06:33 volumiobo volumio[9855]: info: Getting Alsa Cards List without I2S DAC Feb 24 21:06:33 volumiobo sudo[14646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:33 volumiobo sudo[14646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:33 volumiobo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 24 21:06:33 volumiobo qobuz-connect[14261]: 20260224 21:06:33.699 [14261.14261] INFO SampleApp: Stopping Local configuration server Feb 24 21:06:33 volumiobo qobuz-connect[14261]: 20260224 21:06:33.709 [14261.14261] INFO SampleApp: shat down connection on UNIX socket Feb 24 21:06:33 volumiobo systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 24 21:06:33 volumiobo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 21:06:33 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 24 21:06:33 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:33 volumiobo volumio[9855]: info: Asound.conf file written Feb 24 21:06:33 volumiobo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:33 volumiobo sudo[14664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 21:06:33 volumiobo sudo[14664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:33 volumiobo sudo[14646]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:33 volumiobo sudo[14664]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:34 volumiobo volumio[9855]: Found hardware: "RPi-WM8804" "" "" "" "" Feb 24 21:06:34 volumiobo volumio[9855]: Hardware is initialized using a generic method Feb 24 21:06:34 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:34 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:34 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:34 volumiobo sudo[14689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:34 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:34 volumiobo sudo[14689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:34 volumiobo sudo[14689]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:34 volumiobo sudo[14692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:34 volumiobo sudo[14692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:34 volumiobo systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Feb 24 21:06:34 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:34 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:34 volumiobo sudo[14701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:34 volumiobo sudo[14701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo sudo[14703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:34 volumiobo sudo[14703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo sudo[14703]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:34 volumiobo sudo[14705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:34 volumiobo sudo[14705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:34 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:34 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:34 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:34 volumiobo sudo[14701]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:34 volumiobo sudo[14716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:34 volumiobo sudo[14716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo sudo[14719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:34 volumiobo sudo[14719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:34 volumiobo sudo[14721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:34 volumiobo sudo[14721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo sudo[14730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:35 volumiobo sudo[14730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo sudo[14716]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo volumio[9855]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 21:06:35 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:35 volumiobo volumio[9855]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 21:06:35 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:35 volumiobo volumio[9855]: info: Executing endpoint qc_getconfig Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 24 21:06:35 volumiobo sudo[14737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:35 volumiobo sudo[14737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.083 [14658.14658] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.086 [14658.14658] INFO VolumeManager: [0x813258]: Setting new playback volume: 75 Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.086 [14658.14658] INFO VolumeManager: [0x813258]: Setting new mute state: 0 Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.086 [14658.14658] INFO QobuzConnect: [0x813c28]: Client initialized! Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.086 [14658.14658] INFO SampleApp: Starting Avahi advertising, name: volumio_B&O, service name: _qobuz-connect._tcp Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo sudo[14730]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo sudo[14719]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo sudo[14721]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.108 [14658.14658] INFO LocalConfigManager: [0x812b38]: Starting Local Configuration server Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.108 [14658.14658] INFO SampleApp: Starting Local configuration server Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.108 [14658.14658] INFO SampleApp: Connected to UNIX socket client 0x7fd818 Feb 24 21:06:35 volumiobo systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Feb 24 21:06:35 volumiobo systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo sudo[14743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:35 volumiobo sudo[14743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo sudo[14737]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:35 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:35 volumiobo volumio[9855]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 24 21:06:35 volumiobo volumio[9855]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 24 21:06:35 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:35 volumiobo systemd[1]: mpd.service: Deactivated successfully. Feb 24 21:06:35 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:35 volumiobo systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 21:06:35 volumiobo systemd[1]: mpd.service: Consumed 1.338s CPU time. Feb 24 21:06:35 volumiobo systemd[1]: mpd.socket: Deactivated successfully. Feb 24 21:06:35 volumiobo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 21:06:35 volumiobo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 21:06:35 volumiobo sudo[14759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:35 volumiobo sudo[14759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo volumio[9855]: info: Preparing to generate the ALSA configuration file Feb 24 21:06:35 volumiobo sudo[14762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:35 volumiobo sudo[14762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo volumio[9855]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 21:06:35 volumiobo volumio[9855]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 24 21:06:35 volumiobo volumio[9855]: info: Reading ALSA contributions from plugins. Feb 24 21:06:35 volumiobo sudo[14766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:35 volumiobo volumio[9855]: info: Asound.conf file written Feb 24 21:06:35 volumiobo sudo[14766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 21:06:35 volumiobo systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 21:06:35 volumiobo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 24 21:06:35 volumiobo qobuz-connect[14658]: 20260224 21:06:35.224 [14658.14658] INFO SampleApp: Stopping Local configuration server Feb 24 21:06:35 volumiobo sudo[14771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 21:06:35 volumiobo sudo[14771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:35 volumiobo sudo[14771]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 21:06:35 volumiobo systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 21:06:35 volumiobo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:35 volumiobo systemd[1]: shairport-sync.service: Consumed 1.562s CPU time. Feb 24 21:06:35 volumiobo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:35 volumiobo sudo[14766]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo sudo[14762]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:35 volumiobo sudo[14772]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:06:35 volumiobo sudo[14772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 21:06:35 volumiobo sudo[14772]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 24 21:06:36 volumiobo volumio[9855]: Found hardware: "RPi-WM8804" "" "" "" "" Feb 24 21:06:36 volumiobo volumio[9855]: Hardware is initialized using a generic method Feb 24 21:06:36 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:36 volumiobo kernel: wm8804 1-003b: ASoC: error at soc_component_read_no_lock on wm8804.1-003b for register: [0x00000015] -110 Feb 24 21:06:36 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:36 volumiobo sudo[14796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:36 volumiobo sudo[14796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14796]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:36 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:36 volumiobo sudo[14798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:36 volumiobo sudo[14798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:36 volumiobo volumio[9855]: info: Preparing to generate the ALSA configuration file Feb 24 21:06:36 volumiobo sudo[14808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:36 volumiobo sudo[14808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo systemd[1]: mpd.service: Deactivated successfully. Feb 24 21:06:36 volumiobo systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 21:06:36 volumiobo systemd[1]: mpd.service: Consumed 1.145s CPU time. Feb 24 21:06:36 volumiobo systemd[1]: mpd.socket: Deactivated successfully. Feb 24 21:06:36 volumiobo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 21:06:36 volumiobo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 21:06:36 volumiobo sudo[14812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:36 volumiobo sudo[14812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 24 21:06:36 volumiobo volumio[9855]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 24 21:06:36 volumiobo volumio[9855]: info: Reading ALSA contributions from plugins. Feb 24 21:06:36 volumiobo volumio[9855]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 21:06:36 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:36 volumiobo volumio[9855]: info: Shairport-Sync Started Feb 24 21:06:36 volumiobo volumio[9855]: info: Shairport-Sync Started Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 21:06:36 volumiobo systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo sudo[14819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:36 volumiobo sudo[14819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:36 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:36 volumiobo volumio[9855]: info: Asound.conf file unchanged, so no further update is needed Feb 24 21:06:36 volumiobo volumio[9855]: info: Output device has changed, restarting MPD Feb 24 21:06:36 volumiobo sudo[14819]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:36 volumiobo sudo[14828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: Output device has changed, restarting Shairport Sync Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:36 volumiobo sudo[14825]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:06:36 volumiobo sudo[14825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 21:06:36 volumiobo sudo[14825]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14808]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:06:36 volumiobo sudo[14834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:06:36 volumiobo volumio[9855]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo sudo[14835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:06:36 volumiobo sudo[14835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:36 volumiobo sudo[14831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: QobuzConnect: setDeactiveState invoked Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::volumioGetState Feb 24 21:06:36 volumiobo sudo[14834]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:36 volumiobo sudo[14850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:06:36 volumiobo volumio[9855]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 21:06:36 volumiobo sudo[14850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: info: MPD Permissions set Feb 24 21:06:36 volumiobo volumio[9855]: info: TidalConnect service stoped! Feb 24 21:06:36 volumiobo sudo[14848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 24 21:06:36 volumiobo sudo[14856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14812]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:06:36 volumiobo volumio[9855]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:36 volumiobo volumio[9855]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 24 21:06:36 volumiobo volumio[9855]: info: Starting Shairport Sync Feb 24 21:06:36 volumiobo sudo[14877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 24 21:06:36 volumiobo sudo[14877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:06:36 volumiobo sudo[14875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo sudo[14856]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 24 21:06:36 volumiobo sudo[14850]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14848]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:36 volumiobo sudo[14880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 21:06:36 volumiobo volumio[9855]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 24 21:06:36 volumiobo volumio[9855]: Job for vtcs.service canceled. Feb 24 21:06:36 volumiobo volumio[9855]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Feb 24 21:06:36 volumiobo volumio[9855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 21:06:36 volumiobo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 21:06:36 volumiobo volumio[9855]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 24 21:06:36 volumiobo volumio[9855]: Job for vtcs.service canceled. Feb 24 21:06:36 volumiobo volumio[9855]: at ChildProcess.exithandler (node:child_process:421:12) Feb 24 21:06:36 volumiobo volumio[9855]: at ChildProcess.emit (node:events:514:28) Feb 24 21:06:36 volumiobo volumio[9855]: at maybeClose (node:internal/child_process:1105:16) Feb 24 21:06:36 volumiobo volumio[9855]: at Socket. (node:internal/child_process:457:11) Feb 24 21:06:36 volumiobo volumio[9855]: at Socket.emit (node:events:514:28) Feb 24 21:06:36 volumiobo volumio[9855]: at Pipe. (node:net:337:12) { Feb 24 21:06:36 volumiobo volumio[9855]: code: 1, Feb 24 21:06:36 volumiobo volumio[9855]: killed: false, Feb 24 21:06:36 volumiobo volumio[9855]: signal: null, Feb 24 21:06:36 volumiobo volumio[9855]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Feb 24 21:06:36 volumiobo volumio[9855]: stdout: '', Feb 24 21:06:36 volumiobo volumio[9855]: stderr: 'Job for vtcs.service canceled.\n' Feb 24 21:06:36 volumiobo volumio[9855]: } Feb 24 21:06:36 volumiobo volumio[9855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 21:06:36 volumiobo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 24 21:06:36 volumiobo systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 21:06:36 volumiobo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:36 volumiobo systemd[1]: shairport-sync.service: Consumed 1.510s CPU time. Feb 24 21:06:36 volumiobo systemd[1]: mpd.service: Deactivated successfully. Feb 24 21:06:36 volumiobo systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 21:06:37 volumiobo systemd[1]: mpd.socket: Deactivated successfully. Feb 24 21:06:37 volumiobo sudo[14877]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 21:06:37 volumiobo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 21:06:37 volumiobo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 21:06:37 volumiobo systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 21:06:37 volumiobo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 21:06:37 volumiobo sudo[14828]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14875]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo qobuz-connect[14658]: 20260224 21:06:37.121 [14658.14658] INFO SampleApp: shat down connection on UNIX socket Feb 24 21:06:37 volumiobo systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 24 21:06:37 volumiobo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:37 volumiobo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 21:06:37 volumiobo sudo[14831]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14898]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:06:37 volumiobo sudo[14743]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 21:06:37 volumiobo sudo[14880]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14759]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14898]: pam_unix(sudo:session): session closed for user root Feb 24 21:06:37 volumiobo sudo[14919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 21:05' Feb 24 21:06:37 volumiobo sudo[14919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"