Dec 25 20:38:00 volumio-4 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 25 20:38:00 volumio-4 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 25 20:38:00 volumio-4 systemd[1]: welcome.service: Deactivated successfully. Dec 25 20:38:00 volumio-4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 25 20:38:00 volumio-4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 25 20:38:00 volumio-4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 25 20:38:00 volumio-4 welcome[7588]: Resolved ip:[1] 192.168.0.123 Dec 25 20:38:00 volumio-4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 25 20:38:00 volumio-4 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 25 20:38:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:00 volumio-4 go-librespot[1707]: time="2025-12-25T20:38:00+01:00" level=trace msg="sent dealer ping" Dec 25 20:38:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:00 volumio-4 go-librespot[1707]: time="2025-12-25T20:38:00+01:00" level=trace msg="received dealer pong" Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume- Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume- Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume- Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume- Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:26 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:27 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:28 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:29 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume+ Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:38:30+01:00" level=trace msg="sent dealer ping" Dec 25 20:38:30 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume- Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:30 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:38:30+01:00" level=trace msg="received dealer pong" Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::servicePause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePause Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pause Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand pause took 2 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: Dec 25 20:38:31 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:38:31 volumio-4 volumio[1293]: info: Dec 25 20:38:31 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:38:31 volumio-4 volumio[1293]: info: Dec 25 20:38:31 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:38:31 volumio-4 volumio[1293]: info: Dec 25 20:38:31 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:38:31 volumio-4 volumio[1293]: info: Dec 25 20:38:31 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand status took 5 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand status took 4 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand status took 4 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand status took 3 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand status took 2 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70907,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70907,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70907,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70907,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70907,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:38:31 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:38:31 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:38:31 volumio-4 volumio[1293]: info: ------------------------------ 51ms Dec 25 20:38:31 volumio-4 volumio[1293]: info: ------------------------------ 51ms Dec 25 20:38:31 volumio-4 volumio[1293]: info: ------------------------------ 50ms Dec 25 20:38:31 volumio-4 volumio[1293]: info: ------------------------------ 50ms Dec 25 20:38:31 volumio-4 volumio[1293]: info: ------------------------------ 49ms Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:45 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:45 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:45 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:45 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:45 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:45 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:45 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:45 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:47 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:47 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:47 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:47 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:47 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:47 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:47 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:47 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:48 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:48 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:49 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:49 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:49 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:49 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:49 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:49 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:49 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:49 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 25 20:38:50 volumio-4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 25 20:38:50 volumio-4 systemd[1]: setdatetime-helper.service: Consumed 1.169s CPU time. Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:50 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:50 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:51 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:51 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:51 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:51 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:51 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:51 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:51 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:51 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:52 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:52 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:53 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:53 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:53 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:53 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:53 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:53 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:53 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:53 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:54 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:54 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:55 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:55 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:55 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:55 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:55 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:55 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:55 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:55 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:56 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:56 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:57 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:57 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:57 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:57 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:57 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:57 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:57 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:57 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:58 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:58 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:59 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:59 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:59 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:59 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:59 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:59 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:38:59 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:38:59 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:00+01:00" level=trace msg="sent dealer ping" Dec 25 20:39:00 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:00+01:00" level=trace msg="received dealer pong" Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:00 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:00 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:01 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:01 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:02 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:02 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:03 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:03 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPlay Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::play index undefined Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::startPlaybackTimer Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::resume Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand play Dec 25 20:39:04 volumio-4 volumio[1293]: info: Dec 25 20:39:04 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand play took 25 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:39:04 volumio-4 volumio[1293]: info: Dec 25 20:39:04 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:39:04 volumio-4 volumio[1293]: info: Dec 25 20:39:04 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:39:04 volumio-4 volumio[1293]: info: Dec 25 20:39:04 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:39:04 volumio-4 volumio[1293]: info: Dec 25 20:39:04 volumio-4 volumio[1293]: ---------------------------- MPD announces state update: player Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::getState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand status took 14 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand status took 14 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand status took 15 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand status took 15 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand status took 14 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseState Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: ControllerMpd::parseTrackInfo Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72030,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService play Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus pause Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72030,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService play Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus play Dec 25 20:39:04 volumio-4 volumio[1293]: info: Received an update from plugin. extracting info from payload Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72030,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService play Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus play Dec 25 20:39:04 volumio-4 volumio[1293]: info: Received an update from plugin. extracting info from payload Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72030,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService play Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus play Dec 25 20:39:04 volumio-4 volumio[1293]: info: Received an update from plugin. extracting info from payload Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: ControllerMpd::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::servicePushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72030,"duration":463,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Keep on Chooglin'","artist":"Creedence Clearwater Revival","album":"Bayou Country","uri":"USB/ESD-USB/Credence best/06 Keep on Chooglin'.mp3","trackType":"mp3"} Dec 25 20:39:04 volumio-4 volumio[1293]: verbose: CURRENT POSITION 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState stateService play Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::syncState currentStatus play Dec 25 20:39:04 volumio-4 volumio[1293]: info: Received an update from plugin. extracting info from payload Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:04 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:04 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:04 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:05 volumio-4 volumio[1293]: info: ------------------------------ 111ms Dec 25 20:39:05 volumio-4 volumio[1293]: info: ------------------------------ 109ms Dec 25 20:39:05 volumio-4 volumio[1293]: info: ------------------------------ 109ms Dec 25 20:39:05 volumio-4 volumio[1293]: info: ------------------------------ 110ms Dec 25 20:39:05 volumio-4 volumio[1293]: info: ------------------------------ 110ms Dec 25 20:39:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:05 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:05 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:06 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:06 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:07 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:07 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:08 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:08 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:09 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:09 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:10 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:10 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:11 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:11 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:12 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:12 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:13 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:13 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:14 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:14 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:15 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:15 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:16 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:16 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:17 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:17 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:18 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:18 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:19 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:19 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:20 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:20 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:21 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:21 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:22 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:22 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:23 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:23 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:24 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:24 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:25 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:25 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:26 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:26 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:27 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:27 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:28 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:28 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:29 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:29 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:30+01:00" level=trace msg="received accesspoint ping" Dec 25 20:39:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:30+01:00" level=trace msg="received accesspoint pong ack" Dec 25 20:39:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:30+01:00" level=trace msg="sent dealer ping" Dec 25 20:39:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:30 volumio-4 go-librespot[1707]: time="2025-12-25T20:39:30+01:00" level=trace msg="received dealer pong" Dec 25 20:39:30 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:30 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:31 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:31 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 20:39:32 volumio-4 volumio[1293]: info: Getting Alsa Cards List without I2S DAC Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:32 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:32 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:33 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:33 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:34 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:34 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:35 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:35 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:36 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:36 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:37 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:37 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:38 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:38 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:39 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:39 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:40 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:40 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: Restoring Previous Volume level: 100 false true Dec 25 20:39:41 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume100 Dec 25 20:39:41 volumio-4 volumio[1293]: info: Enable softmixer device for audio device number 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioStop Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::stop Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::stPlaybackTimer Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::updateTrackBlock Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrackBlock Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::serviceStop Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::serviceStop Dec 25 20:39:41 volumio-4 volumio[1293]: info: ControllerMpd::stop Dec 25 20:39:41 volumio-4 volumio[1293]: verbose: ControllerMpd::sendMpdCommand stop Dec 25 20:39:41 volumio-4 volumio[1293]: info: Enable softmixer device for audio device undefined Dec 25 20:39:41 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:41 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:41 volumio-4 sudo[9314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:41 volumio-4 sudo[9314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 sudo[9314]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:41 volumio-4 sudo[9316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:41 volumio-4 sudo[9316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:41 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 vtcs[29185]: [2025-12-25 20:39:41.487] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 25 20:39:41 volumio-4 vtcs[29185]: [2025-12-25 20:39:41.487] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 25 20:39:41 volumio-4 vtcs[29185]: [2025-12-25 20:39:41.487] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 25 20:39:41 volumio-4 volumio[1293]: info: Volume configurations have been set Dec 25 20:39:41 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 25 20:39:41 volumio-4 sudo[9326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:41 volumio-4 sudo[9326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 sudo[9328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:41 volumio-4 sudo[9328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 25 20:39:41 volumio-4 volumio[1293]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 25 20:39:41 volumio-4 volumio[1293]: info: Disabling external Volume Control Dec 25 20:39:41 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 25 20:39:41 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 25 20:39:41 volumio-4 systemd[1]: mpd_oled_plugin.service: Consumed 1min 3.292s CPU time. Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 20:39:41 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:41 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 25 20:39:41 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 25 20:39:41 volumio-4 systemd[1]: vtcs.service: Consumed 1.261s CPU time. Dec 25 20:39:41 volumio-4 volumio[1293]: error: MPD error: Error: write EPIPE Dec 25 20:39:41 volumio-4 volumio[1293]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 25 20:39:41 volumio-4 sudo[9328]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:41 volumio-4 sudo[9326]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:41 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:41 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:41 volumio-4 systemd[1]: mpd.service: Consumed 31.824s CPU time. Dec 25 20:39:41 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:41 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:41 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:41 volumio-4 sudo[9342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:41 volumio-4 sudo[9342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 sudo[9344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:41 volumio-4 sudo[9344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:41 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:41 volumio-4 sudo[9355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:41 volumio-4 sudo[9355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 sudo[9365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:41 volumio-4 sudo[9365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:41 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:41 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:41 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:41 volumio-4 sudo[9355]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:41 volumio-4 volumio[1293]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 25 20:39:41 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:41 volumio-4 volumio[1293]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 20:39:41 volumio-4 volumio[1293]: SPOTIFY: SPOTIFY VOLUME 96 Dec 25 20:39:41 volumio-4 volumio[1293]: SPOTIFY: VOLUMIO VOLUME 100 Dec 25 20:39:41 volumio-4 volumio[1293]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 20:39:42 volumio-4 volumio[1293]: info: Setting Spotify Volume from Volumio: 100 Dec 25 20:39:42 volumio-4 volumio[1293]: info: Software Volume ALSA configuration written Dec 25 20:39:42 volumio-4 volumio[1293]: info: Preparing to generate the ALSA configuration file Dec 25 20:39:42 volumio-4 sudo[9368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:42 volumio-4 sudo[9368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 sudo[9365]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 sudo[9344]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 sudo[9342]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 sudo[9370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:42 volumio-4 sudo[9370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 volumio[1293]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 25 20:39:42 volumio-4 volumio[1293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 25 20:39:42 volumio-4 volumio[1293]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 25 20:39:42 volumio-4 volumio[1293]: info: Reading ALSA contributions from plugins. Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 25 20:39:42 volumio-4 qobuz-connect[29218]: 20251225 20:39:42.172 [29218.29218] INFO SampleApp: Stopping Local configuration server Dec 25 20:39:42 volumio-4 sudo[9362]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:42 volumio-4 sudo[9362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:42 volumio-4 sudo[9362]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 20:39:42 volumio-4 volumio[1293]: info: Getting Alsa Cards List without I2S DAC Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 25 20:39:42 volumio-4 volumio[1293]: info: Starting Shairport Sync Dec 25 20:39:42 volumio-4 qobuz-connect[29218]: 20251225 20:39:42.503 [29218.29218] INFO SampleApp: shat down connection on UNIX socket Dec 25 20:39:42 volumio-4 volumio[1293]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 25 20:39:42 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:42 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 25 20:39:42 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:42 volumio-4 volumio[1293]: info: Asound.conf file written Dec 25 20:39:42 volumio-4 sudo[9396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 20:39:42 volumio-4 sudo[9396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 sudo[9401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 25 20:39:42 volumio-4 sudo[9401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 sudo[9401]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:42 volumio-4 sudo[9368]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 sudo[9370]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 20:39:42 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 25 20:39:42 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 25 20:39:42 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 25 20:39:42 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:42 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 20:39:42 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:42 volumio-4 systemd[1]: shairport-sync.service: Consumed 7.025s CPU time. Dec 25 20:39:42 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:42 volumio-4 sudo[9396]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:42 volumio-4 sudo[9410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:42 volumio-4 sudo[9410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 sudo[9408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:42 volumio-4 sudo[9408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 sudo[9408]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:42 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:42 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:42 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:42 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:42 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:42 volumio-4 volumio[1293]: info: Preparing to generate the ALSA configuration file Dec 25 20:39:42 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:42 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:42 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:42 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:42 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:42 volumio-4 sudo[9434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:42 volumio-4 sudo[9434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:42 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:42 volumio-4 volumio[1293]: ALSA lib pcm.c:2666:(snd_pcm_open_noupdate) Unknown PCM volumioMultiRoomServer Dec 25 20:39:42 volumio-4 volumio[1293]: aplay: main:831: audio open error: No such file or directory Dec 25 20:39:42 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:43 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 25 20:39:43 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 25 20:39:43 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 25 20:39:43 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:43 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:43 volumio-4 sudo[9434]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 sudo[9447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:43 volumio-4 sudo[9447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:43 volumio-4 sudo[9450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:43 volumio-4 sudo[9450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 sudo[9450]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 sudo[9455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:43 volumio-4 sudo[9455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:43 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:43 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:43 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:43 volumio-4 sudo[9447]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 sudo[9440]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:43 volumio-4 sudo[9440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:43 volumio-4 sudo[9440]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:43 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:43 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:43 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:43 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:43 volumio-4 sudo[9463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:43 volumio-4 sudo[9463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 25 20:39:43 volumio-4 volumio[1293]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 25 20:39:43 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:43 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 25 20:39:43 volumio-4 volumio[1293]: info: Disabling external Volume Control Dec 25 20:39:43 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:43 volumio-4 sudo[9463]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 sudo[9482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:43 volumio-4 sudo[9482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 sudo[9487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:43 volumio-4 sudo[9487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 sudo[9480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:43 volumio-4 sudo[9480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 sudo[9472]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:43 volumio-4 sudo[9472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:43 volumio-4 sudo[9472]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:43 volumio-4 sudo[9499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:43 volumio-4 sudo[9499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 sudo[9503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:43 volumio-4 sudo[9503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:43 volumio-4 volumio[1293]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 25 20:39:43 volumio-4 volumio[1293]: info: Sending Spotify command with payload to local API: /player/volume Dec 25 20:39:43 volumio-4 volumio[1293]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 25 20:39:43 volumio-4 volumio[1293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 25 20:39:43 volumio-4 volumio[1293]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 25 20:39:43 volumio-4 volumio[1293]: info: Reading ALSA contributions from plugins. Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:44 volumio-4 sudo[9482]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:44 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:44 volumio-4 sudo[9487]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:44 volumio-4 volumio[1293]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 25 20:39:44 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:44 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:44 volumio-4 volumio[1293]: info: Shairport-Sync Started Dec 25 20:39:44 volumio-4 volumio[1293]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Dec 25 20:39:44 volumio-4 volumio[1293]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 20:39:44 volumio-4 volumio[1293]: info: Executing endpoint qc_getconfig Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 25 20:39:44 volumio-4 sudo[9510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:44 volumio-4 sudo[9510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 sudo[9499]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 sudo[9503]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 sudo[9480]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.201 [9398.9398] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.221 [9398.9398] INFO VolumeManager: [0x80d268]: Setting new playback volume: 75 Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.222 [9398.9398] INFO VolumeManager: [0x80d268]: Setting new mute state: 0 Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.222 [9398.9398] INFO QobuzConnect: [0x80dc38]: Client initialized! Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.222 [9398.9398] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 25 20:39:44 volumio-4 volumio[1293]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 25 20:39:44 volumio-4 volumio[1293]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.276 [9398.9398] INFO LocalConfigManager: [0x80cb48]: Starting Local Configuration server Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.276 [9398.9398] INFO SampleApp: Starting Local configuration server Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.277 [9398.9398] INFO SampleApp: Connected to UNIX socket client 0x7f7818 Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 sudo[9510]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 sudo[9519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 sudo[9519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.352 [9398.9398] INFO SampleApp: Playback volume changed: 75 Dec 25 20:39:44 volumio-4 sudo[9518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:44 volumio-4 sudo[9518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 sudo[9524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:44 volumio-4 sudo[9524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:44 volumio-4 volumio[1293]: info: Starting Shairport Sync Dec 25 20:39:44 volumio-4 qobuz-connect[9398]: 20251225 20:39:44.485 [9398.9398] INFO SampleApp: Stopping Local configuration server Dec 25 20:39:44 volumio-4 volumio[1293]: info: Starting Shairport Sync Dec 25 20:39:44 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 25 20:39:44 volumio-4 sudo[9539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 20:39:44 volumio-4 sudo[9539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 volumio[1293]: info: Asound.conf file written Dec 25 20:39:44 volumio-4 sudo[9541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 20:39:44 volumio-4 sudo[9541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 20:39:44 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 20:39:44 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:44 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.428s CPU time. Dec 25 20:39:44 volumio-4 sudo[9545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 25 20:39:44 volumio-4 sudo[9545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 sudo[9545]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 25 20:39:44 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 25 20:39:44 volumio-4 volumio[1293]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 25 20:39:44 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:44 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:44 volumio-4 sudo[9539]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:44 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 20:39:44 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 20:39:44 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:44 volumio-4 sudo[9555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:44 volumio-4 sudo[9555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:44 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:44 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:44 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:44 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:44 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:44 volumio-4 sudo[9553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:44 volumio-4 sudo[9553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:44 volumio-4 volumio[1293]: info: Preparing to generate the ALSA configuration file Dec 25 20:39:44 volumio-4 sudo[9541]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:44 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:44 volumio-4 systemd[1]: mpd.service: Consumed 1.237s CPU time. Dec 25 20:39:44 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:44 volumio-4 sudo[9553]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:44 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:44 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:44 volumio-4 sudo[9565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:44 volumio-4 sudo[9565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:45 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:45 volumio-4 sudo[9584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:45 volumio-4 sudo[9584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 25 20:39:45 volumio-4 sudo[9565]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:45 volumio-4 volumio[1293]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 25 20:39:45 volumio-4 volumio[1293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 25 20:39:45 volumio-4 volumio[1293]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 25 20:39:45 volumio-4 volumio[1293]: info: Reading ALSA contributions from plugins. Dec 25 20:39:45 volumio-4 sudo[9596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:45 volumio-4 sudo[9596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 sudo[9593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:45 volumio-4 sudo[9593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:45 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:45 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:45 volumio-4 volumio[1293]: info: Shairport-Sync Started Dec 25 20:39:45 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:45 volumio-4 volumio[1293]: info: Shairport-Sync Started Dec 25 20:39:45 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:45 volumio-4 sudo[9588]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:45 volumio-4 sudo[9588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 sudo[9588]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 25 20:39:45 volumio-4 volumio[1293]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 25 20:39:45 volumio-4 sudo[9593]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:45 volumio-4 sudo[9584]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:45 volumio-4 volumio[1293]: info: Starting Shairport Sync Dec 25 20:39:45 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:45 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:45 volumio-4 volumio[1293]: info: Asound.conf file unchanged, so no further update is needed Dec 25 20:39:45 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:45 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:45 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:45 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:45 volumio-4 sudo[9622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:45 volumio-4 sudo[9622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:45 volumio-4 sudo[9625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 20:39:45 volumio-4 sudo[9625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:45 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:45 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:45 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:45 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:45 volumio-4 sudo[9628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:45 volumio-4 sudo[9628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:45 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:45 volumio-4 sudo[9633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:45 volumio-4 sudo[9628]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:45 volumio-4 sudo[9633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 volumio[1293]: info: Preparing to generate the ALSA configuration file Dec 25 20:39:45 volumio-4 sudo[9643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:45 volumio-4 sudo[9643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:45 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 20:39:46 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 20:39:46 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:46 volumio-4 sudo[9645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:46 volumio-4 sudo[9645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:46 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:46 volumio-4 volumio[1293]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 25 20:39:46 volumio-4 volumio[1293]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 25 20:39:46 volumio-4 volumio[1293]: info: VolumeController::SetAlsaVolume100 Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreStateMachine::pushState Dec 25 20:39:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioPushState Dec 25 20:39:46 volumio-4 sudo[9653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:46 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output update for this device Dec 25 20:39:46 volumio-4 sudo[9653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 volumio[1293]: info: MRS: Pushing multiroomSync output Dec 25 20:39:46 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:46 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:46 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:46 volumio-4 volumio[1293]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 25 20:39:46 volumio-4 volumio[1293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 25 20:39:46 volumio-4 volumio[1293]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 25 20:39:46 volumio-4 volumio[1293]: info: Reading ALSA contributions from plugins. Dec 25 20:39:46 volumio-4 volumio[1293]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 20:39:46 volumio-4 volumio[1293]: SPOTIFY: SPOTIFY VOLUME 100 Dec 25 20:39:46 volumio-4 volumio[1293]: SPOTIFY: VOLUMIO VOLUME 100 Dec 25 20:39:46 volumio-4 volumio[1293]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 20:39:46 volumio-4 sudo[9660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 25 20:39:46 volumio-4 sudo[9660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:46 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:46 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:46 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:46 volumio-4 sudo[9664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 25 20:39:46 volumio-4 sudo[9625]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 qobuz-connect[9398]: 20251225 20:39:46.282 [9398.9398] INFO SampleApp: shat down connection on UNIX socket Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: Shairport-Sync Started Dec 25 20:39:46 volumio-4 volumio[1293]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 25 20:39:46 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:46 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 25 20:39:46 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:46 volumio-4 volumio[1293]: info: Starting Shairport Sync Dec 25 20:39:46 volumio-4 volumio[1293]: info: Asound.conf file unchanged, so no further update is needed Dec 25 20:39:46 volumio-4 volumio[1293]: info: Output device has changed, restarting MPD Dec 25 20:39:46 volumio-4 volumio[1293]: info: Output device has changed, restarting Shairport Sync Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 20:39:46 volumio-4 sudo[9680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 20:39:46 volumio-4 sudo[9673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 20:39:46 volumio-4 sudo[9680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 sudo[9680]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 sudo[9653]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9645]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9643]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 20:39:46 volumio-4 sudo[9697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:46 volumio-4 sudo[9697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 20:39:46 volumio-4 volumio[1293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 25 20:39:46 volumio-4 sudo[9687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:46 volumio-4 volumio[1293]: info: QobuzConnect: setDeactiveState invoked Dec 25 20:39:46 volumio-4 volumio[1293]: info: CoreCommandRouter::volumioGetState Dec 25 20:39:46 volumio-4 volumio[1293]: info: CorePlayQueue::getTrack 2 Dec 25 20:39:46 volumio-4 sudo[9663]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:46 volumio-4 sudo[9663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:46 volumio-4 sudo[9663]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:46 volumio-4 sudo[9519]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9524]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 25 20:39:46 volumio-4 sudo[9622]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9518]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:46 volumio-4 sudo[9706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:46 volumio-4 sudo[9660]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9664]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:46 volumio-4 sudo[9711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 25 20:39:46 volumio-4 sudo[9711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:47 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 25 20:39:47 volumio-4 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 20:39:47 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 20:39:47 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 25 20:39:47 volumio-4 sudo[9721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 25 20:39:47 volumio-4 sudo[9721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:47 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 volumio[1293]: info: MPD Permissions set Dec 25 20:39:47 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:47 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:47 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:47 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 25 20:39:47 volumio-4 volumio[1293]: info: TidalConnect service stoped! Dec 25 20:39:47 volumio-4 volumio[1293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 20:39:47 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:47 volumio-4 volumio[1293]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 25 20:39:47 volumio-4 volumio[1293]: Job for vtcs.service canceled. Dec 25 20:39:47 volumio-4 volumio[1293]: at ChildProcess.exithandler (node:child_process:421:12) Dec 25 20:39:47 volumio-4 volumio[1293]: at ChildProcess.emit (node:events:514:28) Dec 25 20:39:47 volumio-4 volumio[1293]: at maybeClose (node:internal/child_process:1105:16) Dec 25 20:39:47 volumio-4 volumio[1293]: at Socket. (node:internal/child_process:457:11) Dec 25 20:39:47 volumio-4 volumio[1293]: at Socket.emit (node:events:514:28) Dec 25 20:39:47 volumio-4 volumio[1293]: at Pipe. (node:net:337:12) { Dec 25 20:39:47 volumio-4 volumio[1293]: code: 1, Dec 25 20:39:47 volumio-4 volumio[1293]: killed: false, Dec 25 20:39:47 volumio-4 volumio[1293]: signal: null, Dec 25 20:39:47 volumio-4 volumio[1293]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 25 20:39:47 volumio-4 volumio[1293]: stdout: '', Dec 25 20:39:47 volumio-4 volumio[1293]: stderr: 'Job for vtcs.service canceled.\n' Dec 25 20:39:47 volumio-4 volumio[1293]: } Dec 25 20:39:47 volumio-4 volumio[1293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 20:39:47 volumio-4 sudo[9706]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:47 volumio-4 sudo[9721]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 mpd_monitor.sh[9659]: MPD error: Connection reset by peer Dec 25 20:39:47 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:47 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:47 volumio-4 mpd_monitor.sh[29334]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 25 20:39:47 volumio-4 sudo[9739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 25 20:39:47 volumio-4 mpd_monitor.sh[9741]: mpd: no process found Dec 25 20:39:47 volumio-4 sudo[9739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 20:39:47 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:47 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:47 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:47 volumio-4 sudo[9697]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 20:39:47 volumio-4 sudo[9673]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 sudo[9711]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 25 20:39:47 volumio-4 sudo[9745]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:47 volumio-4 sudo[9745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:47 volumio-4 sudo[9745]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:47 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 20:39:47 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:47 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 25 20:39:47 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 20:39:47 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 20:39:47 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 20:39:47 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 20:39:47 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 25 20:39:47 volumio-4 sudo[9739]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:48 volumio-4 sudo[9764]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 20:39:48 volumio-4 sudo[9764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 20:39:48 volumio-4 sudo[9764]: pam_unix(sudo:session): session closed for user root Dec 25 20:39:48 volumio-4 sudo[9784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-25 20:38' Dec 25 20:39:48 volumio-4 sudo[9784]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"