Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio volumio[1524]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:38:01 volumio sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:38:01 volumio sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:38:01 volumio sudo[2725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:38:01 volumio sudo[2723]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:38:01 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:38:01 volumio systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Oct 13 19:38:01 volumio mpd_oled[2733]: mpd_oled: error: -c: missing argument Oct 13 19:38:01 volumio systemd[1]: mpd_oled_plugin.service: Control process exited, code=exited, status=1/FAILURE Oct 13 19:38:01 volumio systemd[1]: mpd_oled_plugin.service: Failed with result 'exit-code'. Oct 13 19:38:01 volumio systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Oct 13 19:38:01 volumio systemd[1]: mpd_oled_plugin.service: Consumed 8min 32.532s CPU time. Oct 13 19:38:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:38:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:38:01 volumio systemd[1]: mpd.service: Consumed 32.219s CPU time. Oct 13 19:38:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:38:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:38:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:38:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:38:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:01 volumio sudo[2743]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:38:01 volumio sudo[2743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:01 volumio sudo[2743]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:05 volumio mpd[2746]: 2026-10-13T19:38:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 19:38:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 19:38:05 volumio sudo[2725]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:05 volumio volumio[1524]: error: updateQueue error: null Oct 13 19:38:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:05 volumio systemd[1]: Starting mpd_oled_plugin.service - MPD OLED Plugin... Oct 13 19:38:05 volumio sudo[2843]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:05 volumio sudo[2843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio sudo[2843]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioPause Oct 13 19:38:14 volumio volumio[1524]: info: CoreStateMachine::pause Oct 13 19:38:14 volumio volumio[1524]: info: CoreStateMachine::stPlaybackTimer Oct 13 19:38:14 volumio volumio[1524]: info: CoreStateMachine::servicePause Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::servicePause Oct 13 19:38:14 volumio volumio[1524]: info: ControllerMpd::pause Oct 13 19:38:14 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand pause Oct 13 19:38:14 volumio volumio[1524]: info: sendMpdCommand pause took 1 milliseconds Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio sudo[3034]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:14 volumio sudo[3034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioPause Oct 13 19:38:19 volumio volumio[1524]: info: CoreStateMachine::pause Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio sudo[3034]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioPause Oct 13 19:38:21 volumio volumio[1524]: info: CoreStateMachine::pause Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio sudo[3193]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:23 volumio sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:29 volumio sudo[3193]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: error: error Oct 13 19:38:31 volumio volumio[1524]: error: error Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioPlay Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::play index 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::stop Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::updateTrackBlock Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrackBlock Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::stPlaybackTimer Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:38:31 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:38:31 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::serviceStop Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::serviceStop Oct 13 19:38:31 volumio volumio[1524]: info: ControllerMpd::stop Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 19:38:31 volumio volumio[1524]: info: sendMpdCommand stop took 16 milliseconds Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::play index undefined Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreStateMachine::startPlaybackTimer Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::clearAddPlayTracks cdda:///8 Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 19:38:31 volumio volumio[1524]: info: sendMpdCommand stop took 1 milliseconds Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 19:38:31 volumio volumio[1524]: info: Oct 13 19:38:31 volumio volumio[1524]: ---------------------------- MPD announces system playlist update Oct 13 19:38:31 volumio volumio[1524]: info: Ignoring MPD Status Update Oct 13 19:38:31 volumio volumio[1524]: info: sendMpdCommand clear took 7 milliseconds Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand add "cdda:///8" Oct 13 19:38:31 volumio volumio[1524]: error: updateQueue error: null Oct 13 19:38:31 volumio volumio[1524]: info: ------------------------------ 3ms Oct 13 19:38:31 volumio volumio[1524]: info: Oct 13 19:38:31 volumio volumio[1524]: ---------------------------- MPD announces system playlist update Oct 13 19:38:31 volumio volumio[1524]: info: Ignoring MPD Status Update Oct 13 19:38:31 volumio volumio[1524]: info: sendMpdCommand add "cdda:///8" took 3 milliseconds Oct 13 19:38:31 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand play Oct 13 19:38:31 volumio volumio[1524]: info: ------------------------------ 2ms Oct 13 19:38:31 volumio volumio[1524]: info: sendMpdCommand play took 1 milliseconds Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:31 volumio sudo[3366]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:31 volumio sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:32 volumio volumio[1524]: info: [cd-plugin] CD track playback started. Oct 13 19:38:33 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:33 volumio volumio[1524]: info: [cd-plugin] First attempt to set CD whisper speed. Oct 13 19:38:34 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:34 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 1 of 5. Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: Oct 13 19:38:34 volumio volumio[1524]: ---------------------------- MPD announces state update: player Oct 13 19:38:34 volumio volumio[1524]: info: ControllerMpd::getState Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand status Oct 13 19:38:34 volumio volumio[1524]: info: Oct 13 19:38:34 volumio volumio[1524]: ---------------------------- MPD announces state update: player Oct 13 19:38:34 volumio volumio[1524]: info: sendMpdCommand status took 46 milliseconds Oct 13 19:38:34 volumio volumio[1524]: info: ControllerMpd::getState Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand status Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::parseState Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 19:38:34 volumio volumio[1524]: info: sendMpdCommand status took 1 milliseconds Oct 13 19:38:34 volumio volumio[1524]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::parseState Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::parseTrackInfo Oct 13 19:38:34 volumio volumio[1524]: info: ControllerMpd::pushState Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":492,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8","artist":null,"album":null,"uri":"cdda:///8","trackType":"CD Audio"} Oct 13 19:38:34 volumio volumio[1524]: verbose: CURRENT POSITION 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::syncState stateService play Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::syncState currentStatus stop Oct 13 19:38:34 volumio volumio[1524]: info: ------------------------------ 57ms Oct 13 19:38:34 volumio volumio[1524]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 19:38:34 volumio volumio[1524]: verbose: ControllerMpd::parseTrackInfo Oct 13 19:38:34 volumio volumio[1524]: info: ControllerMpd::pushState Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":492,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8","artist":null,"album":null,"uri":"cdda:///8","trackType":"CD Audio"} Oct 13 19:38:34 volumio volumio[1524]: verbose: CURRENT POSITION 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::syncState stateService play Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::syncState currentStatus play Oct 13 19:38:34 volumio volumio[1524]: info: Received an update from plugin. extracting info from payload Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:38:34 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:38:34 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:38:34 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:38:34 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:38:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:34 volumio volumio[1524]: info: ------------------------------ 39ms Oct 13 19:38:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:36 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 2 of 5. Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:37 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:37 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 3 of 5. Oct 13 19:38:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:38 volumio sudo[3366]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:40 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 4 of 5. Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio sudo[3522]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:40 volumio sudo[3522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:41 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:38:41 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 5 of 5. Oct 13 19:38:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:47 volumio sudo[3522]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:49 volumio sudo[3696]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:49 volumio sudo[3696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:50 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:50 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:51 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:51 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:51 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:51 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:51 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:51 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:51 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:51 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:51 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:51 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:52 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:52 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:52 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:52 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:52 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:52 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:52 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:52 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:52 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:52 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:53 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:53 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:53 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:53 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:53 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:53 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:53 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:53 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:53 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:53 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:54 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:54 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:54 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:54 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:54 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:54 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:54 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:54 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:54 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:54 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:55 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:55 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:55 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:55 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:55 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:55 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:55 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:55 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:55 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:55 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio sudo[3696]: pam_unix(sudo:session): session closed for user root Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:56 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:56 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:57 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:57 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:57 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:57 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:57 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:57 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:57 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:57 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:57 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:57 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioPause Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::pause Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::stPlaybackTimer Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::servicePause Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::servicePause Oct 13 19:38:58 volumio volumio[1524]: info: ControllerMpd::pause Oct 13 19:38:58 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand pause Oct 13 19:38:58 volumio volumio[1524]: info: Oct 13 19:38:58 volumio volumio[1524]: ---------------------------- MPD announces state update: player Oct 13 19:38:58 volumio volumio[1524]: info: sendMpdCommand pause took 8 milliseconds Oct 13 19:38:58 volumio volumio[1524]: info: ControllerMpd::getState Oct 13 19:38:58 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand status Oct 13 19:38:58 volumio volumio[1524]: info: sendMpdCommand status took 2 milliseconds Oct 13 19:38:58 volumio volumio[1524]: verbose: ControllerMpd::parseState Oct 13 19:38:58 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 19:38:58 volumio volumio[1524]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 19:38:58 volumio volumio[1524]: verbose: ControllerMpd::parseTrackInfo Oct 13 19:38:58 volumio volumio[1524]: info: ControllerMpd::pushState Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22466,"duration":492,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8","artist":null,"album":null,"uri":"cdda:///8","trackType":"CD Audio"} Oct 13 19:38:58 volumio volumio[1524]: verbose: CURRENT POSITION 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::syncState stateService pause Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::syncState currentStatus pause Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:38:58 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:38:58 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreStateMachine::stPlaybackTimer Oct 13 19:38:58 volumio volumio[1524]: info: ------------------------------ 17ms Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio sudo[3867]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:38:58 volumio sudo[3867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:58 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:58 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:59 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:59 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:59 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:59 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:59 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:59 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:59 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:59 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:38:59 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:38:59 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:00 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:00 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:01 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:01 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:02 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:02 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:03 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:03 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:04 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:04 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:05 volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:05 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:05 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:06 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:06 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio sudo[4040]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:39:07 volumio sudo[4040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:07 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:07 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:08 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:08 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:09 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:09 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:10 volumio volumio[1524]: info: Disabling plugin mpd_oled Oct 13 19:39:10 volumio sudo[4093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Oct 13 19:39:10 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:10 volumio sudo[4093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:10 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:10 volumio sudo[4040]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio systemd[1]: mpd_oled_plugin.service: Control process exited, code=killed, status=15/TERM Oct 13 19:39:10 volumio volumio[1524]: info: Asound.conf file written Oct 13 19:39:10 volumio sudo[4102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:39:10 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio mpd_oled[4099]: mpd_oled: error: -c: missing argument Oct 13 19:39:10 volumio systemd[1]: mpd_oled_plugin.service: Control process exited, code=exited, status=1/FAILURE Oct 13 19:39:10 volumio systemd[1]: mpd_oled_plugin.service: Failed with result 'signal'. Oct 13 19:39:10 volumio systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Oct 13 19:39:10 volumio systemd[1]: mpd_oled_plugin.service: Consumed 50.638s CPU time. Oct 13 19:39:10 volumio sudo[4093]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:39:10 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 13 19:39:10 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 13 19:39:10 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 13 19:39:10 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:10 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:10 volumio sudo[4109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:10 volumio sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:10 volumio sudo[4109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio sudo[4109]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:10 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:10 volumio vtcs[2859]: [2026-10-13 19:39:10.333] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 13 19:39:10 volumio vtcs[2859]: [2026-10-13 19:39:10.338] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 13 19:39:10 volumio volumio[1524]: info: Done. Oct 13 19:39:10 volumio sudo[4128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:10 volumio sudo[4128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio sudo[4132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:10 volumio sudo[4132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:10 volumio systemd[1]: mpd.service: Consumed 3.996s CPU time. Oct 13 19:39:10 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:10 volumio volumio[1524]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Oct 13 19:39:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:10 volumio sudo[4138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:10 volumio sudo[4138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:10 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:10 volumio sudo[4149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:10 volumio sudo[4149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:10 volumio sudo[4138]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:39:10 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:39:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:39:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:10 volumio systemd[1]: shairport-sync.service: Consumed 50.001s CPU time. Oct 13 19:39:10 volumio sudo[4152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:10 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:10 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:39:10 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:10 volumio sudo[4149]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio systemd[1]: vtcs.service: Consumed 5.783s CPU time. Oct 13 19:39:10 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:10 volumio sudo[4128]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio sudo[4132]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:39:10 volumio qobuz-connect[2702]: 20261013 19:39:10.895 [2702.2702] INFO SampleApp: Stopping Local configuration server Oct 13 19:39:10 volumio sudo[4151]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:10 volumio sudo[4151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:10 volumio sudo[4151]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:10 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:10 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:11 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:11 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio qobuz-connect[2702]: 20261013 19:39:12.041 [2702.2702] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:39:12 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:39:12 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:39:12 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:12 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:12 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio volumio[1524]: info: Executing endpoint qc_getconfig Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.238 [4192.4192] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.249 [4192.4192] INFO VolumeManager: [0x1ea5068]: Setting new playback volume: 75 Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.249 [4192.4192] INFO VolumeManager: [0x1ea5068]: Setting new mute state: 0 Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.249 [4192.4192] INFO AudioStreamManager: [0x1ea4dc0]: Setting new audio download buffer size: 1048576 Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.249 [4192.4192] INFO QobuzConnect: [0x1ea5930]: Client initialized! Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.249 [4192.4192] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 13 19:39:12 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:39:12 volumio volumio[1524]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.283 [4192.4192] INFO LocalConfigManager: [0x1ea4ae8]: Starting Local Configuration server Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.283 [4192.4192] INFO SampleApp: Starting Local configuration server Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.284 [4192.4192] INFO SampleApp: Connected to UNIX socket client 0x1e8f818 Oct 13 19:39:12 volumio qobuz-connect[4192]: 20261013 19:39:12.389 [4192.4192] INFO SampleApp: Playback volume changed: 75 Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:12 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:12 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:13 volumio volumio[1524]: info: Enabling plugin mpd_oled Oct 13 19:39:13 volumio volumio[1524]: info: Loading plugin "mpd_oled"... Oct 13 19:39:13 volumio volumio[1524]: info: Applying required configuration parameters for plugin mpd_oled Oct 13 19:39:13 volumio volumio[1524]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Oct 13 19:39:13 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:13 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:13 volumio volumio[1524]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 13 19:39:13 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:13 volumio volumio[1524]: info: Asound.conf file written Oct 13 19:39:13 volumio sudo[4222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:39:13 volumio sudo[4222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio sudo[4222]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:13 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:39:13 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 13 19:39:13 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 13 19:39:13 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 13 19:39:13 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:13 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:13 volumio sudo[4229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:13 volumio sudo[4229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio sudo[4229]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:13 volumio sudo[4233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:13 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:13 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:13 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:13 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:13 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:13 volumio systemd[1]: mpd.service: Consumed 2.947s CPU time. Oct 13 19:39:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:13 volumio volumio[1524]: info: PLUGIN START: mpd_oled Oct 13 19:39:13 volumio sudo[4262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:13 volumio sudo[4262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 13 19:39:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:13 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:13 volumio volumio[1524]: info: Done. Oct 13 19:39:13 volumio sudo[4262]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:13 volumio sudo[4270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Oct 13 19:39:13 volumio sudo[4272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Oct 13 19:39:13 volumio sudo[4270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio sudo[4272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:13 volumio sudo[4270]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio sudo[4277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:14 volumio sudo[4277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:14 volumio volumio[1524]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 13 19:39:14 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:14 volumio volumio[1524]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Oct 13 19:39:14 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:14 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:14 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:14 volumio volumio[1524]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Oct 13 19:39:14 volumio sudo[4268]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:14 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:14 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:14 volumio sudo[4284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:14 volumio sudo[4284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:14 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:14 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:14 volumio sudo[4277]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio sudo[4304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:14 volumio sudo[4304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio volumio[1524]: info: Asound.conf file unchanged, so no further update is needed Oct 13 19:39:14 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:14 volumio sudo[4284]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio sudo[4308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:39:14 volumio sudo[4308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:39:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:39:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:14 volumio systemd[1]: shairport-sync.service: Consumed 1.561s CPU time. Oct 13 19:39:14 volumio sudo[4316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:14 volumio sudo[4316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio sudo[4310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:14 volumio sudo[4310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio sudo[4312]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:14 volumio sudo[4312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:14 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio sudo[4312]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:14 volumio sudo[4304]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio sudo[4328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:14 volumio sudo[4328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:39:14 volumio qobuz-connect[4192]: 20261013 19:39:14.631 [4192.4192] INFO SampleApp: Stopping Local configuration server Oct 13 19:39:14 volumio sudo[4341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:14 volumio sudo[4358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:14 volumio sudo[4358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio sudo[4341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:14 volumio sudo[4308]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:14 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:14 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio sudo[4358]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:14 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:14 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:14 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:14 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:39:14 volumio sudo[4364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:14 volumio sudo[4364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:14 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:39:14 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:15 volumio sudo[4328]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:15 volumio sudo[4374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:15 volumio sudo[4374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:15 volumio sudo[4341]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:39:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:39:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:15 volumio sudo[4371]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:15 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:15 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:15 volumio sudo[4374]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:15 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:15 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:15 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio qobuz-connect[4192]: 20261013 19:39:16.299 [4192.4192] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:39:16 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:39:16 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:39:16 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:16 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:16 volumio sudo[4310]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:16 volumio sudo[4364]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio volumio[1524]: info: Executing endpoint qc_getconfig Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.512 [4412.4412] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:39:16 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:39:16 volumio volumio[1524]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.518 [4412.4412] INFO VolumeManager: [0x184e068]: Setting new playback volume: 75 Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.518 [4412.4412] INFO VolumeManager: [0x184e068]: Setting new mute state: 0 Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.518 [4412.4412] INFO AudioStreamManager: [0x184ddc0]: Setting new audio download buffer size: 1048576 Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.518 [4412.4412] INFO QobuzConnect: [0x184e930]: Client initialized! Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.518 [4412.4412] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.547 [4412.4412] INFO LocalConfigManager: [0x184dae8]: Starting Local Configuration server Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.547 [4412.4412] INFO SampleApp: Starting Local configuration server Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.548 [4412.4412] INFO SampleApp: Connected to UNIX socket client 0x1838818 Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio qobuz-connect[4412]: 20261013 19:39:16.661 [4412.4412] INFO SampleApp: Playback volume changed: 75 Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:16 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:16 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:17 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:17 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:17 volumio sudo[4442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:39:17 volumio sudo[4442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:17 volumio sudo[4442]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: TidalConnect service started! Oct 13 19:39:17 volumio volumio[1524]: info: Executing endpoint tc_getconfig Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 13 19:39:17 volumio vtcs[4447]: STARTING TidalConnect services, version: 1.6.1 Oct 13 19:39:17 volumio vtcs[4447]: STARTED TidalConnect services. Oct 13 19:39:17 volumio volumio[1524]: info: Executing endpoint tc_connect Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 13 19:39:17 volumio volumio[1524]: info: Connecting to TidalConnect Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:17 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:17 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:17 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:17 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:17 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:17 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:17 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:17 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:18 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:18 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:18 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:18 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:18 volumio sudo[4480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:39:18 volumio sudo[4480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:18 volumio sudo[4480]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:18 volumio mpd[4381]: 2026-10-13T19:39:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 19:39:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 19:39:18 volumio sudo[4316]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:18 volumio sudo[4116]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:18 volumio sudo[4233]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:18 volumio volumio[1524]: error: MPD error: The expression evaluated to a falsy value: Oct 13 19:39:18 volumio volumio[1524]: assert.ok(self.idling) Oct 13 19:39:18 volumio volumio[1524]: error: The expression evaluated to a falsy value: Oct 13 19:39:18 volumio volumio[1524]: assert.ok(self.idling) Oct 13 19:39:18 volumio volumio[1524]: error: MPD error: The expression evaluated to a falsy value: Oct 13 19:39:18 volumio volumio[1524]: assert.ok(self.idling) Oct 13 19:39:18 volumio volumio[1524]: error: The expression evaluated to a falsy value: Oct 13 19:39:18 volumio volumio[1524]: assert.ok(self.idling) Oct 13 19:39:18 volumio volumio[1524]: error: updateQueue error: null Oct 13 19:39:18 volumio systemd[1]: Starting mpd_oled_plugin.service - MPD OLED Plugin... Oct 13 19:39:18 volumio sudo[4495]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:39:18 volumio sudo[4495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:18 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:18 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:19 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:19 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:20 volumio volumio[1524]: info: TidalConnect service started! Oct 13 19:39:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:20 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:20 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: TidalConnect service started! Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:21 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:21 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:22 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:22 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:23 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:23 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:24 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:24 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio sudo[4495]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:25 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:25 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:26 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:26 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:27 volumio sudo[4675]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:39:27 volumio sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:27 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:27 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioPlay Oct 13 19:39:28 volumio volumio[1524]: info: CoreStateMachine::play index undefined Oct 13 19:39:28 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreStateMachine::startPlaybackTimer Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: ControllerMpd::resume Oct 13 19:39:28 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand play Oct 13 19:39:28 volumio volumio[1524]: info: sendMpdCommand play took 2 milliseconds Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:28 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:28 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:29 volumio volumio[1524]: info: [cd-plugin] CD track playback started. Oct 13 19:39:29 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:29 volumio volumio[1524]: info: [cd-plugin] First attempt to set CD whisper speed. Oct 13 19:39:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:29 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:29 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:30 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:30 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 1 of 5. Oct 13 19:39:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:30 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:30 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:31 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:31 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioPlay Oct 13 19:39:32 volumio volumio[1524]: info: CoreStateMachine::play index undefined Oct 13 19:39:32 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreStateMachine::startPlaybackTimer Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: ControllerMpd::resume Oct 13 19:39:32 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand play Oct 13 19:39:32 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:32 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 2 of 5. Oct 13 19:39:32 volumio volumio[1524]: info: sendMpdCommand play took 60 milliseconds Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:32 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:32 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:33 volumio volumio[1524]: info: [cd-plugin] CD track playback started. Oct 13 19:39:33 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:33 volumio volumio[1524]: info: [cd-plugin] First attempt to set CD whisper speed. Oct 13 19:39:33 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:33 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:33 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:33 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:33 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:33 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:33 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:33 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:33 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:33 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:34 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:34 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 1 of 5. Oct 13 19:39:34 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:34 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 3 of 5. Oct 13 19:39:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:34 volumio sudo[4675]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:34 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:34 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:35 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:35 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:36 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 2 of 5. Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:36 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 4 of 5. Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioPlay Oct 13 19:39:36 volumio volumio[1524]: info: CoreStateMachine::play index undefined Oct 13 19:39:36 volumio volumio[1524]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: CoreStateMachine::startPlaybackTimer Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: ControllerMpd::resume Oct 13 19:39:36 volumio volumio[1524]: verbose: ControllerMpd::sendMpdCommand play Oct 13 19:39:36 volumio volumio[1524]: info: sendMpdCommand play took 1 milliseconds Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio sudo[4872]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:39:36 volumio sudo[4872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:36 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:36 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: error: error Oct 13 19:39:37 volumio volumio[1524]: error: error Oct 13 19:39:37 volumio volumio[1524]: info: [cd-plugin] CD track playback started. Oct 13 19:39:37 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:37 volumio volumio[1524]: info: [cd-plugin] First attempt to set CD whisper speed. Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:37 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:37 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 3 of 5. Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 5 of 5. Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:38 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 1 of 5. Oct 13 19:39:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:38 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:38 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:39 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:39 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:40 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 4 of 5. Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:40 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 2 of 5. Oct 13 19:39:40 volumio volumio[1524]: info: Disabling plugin mpd_oled Oct 13 19:39:40 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:40 volumio sudo[4967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Oct 13 19:39:40 volumio sudo[4967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:40 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio volumio[1524]: info: Asound.conf file written Oct 13 19:39:40 volumio sudo[4272]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio systemd[1]: mpd_oled_plugin.service: Control process exited, code=killed, status=15/TERM Oct 13 19:39:40 volumio sudo[4872]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio sudo[4975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:39:40 volumio sudo[4975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio sudo[4975]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio mpd_oled[4973]: mpd_oled: error: -c: missing argument Oct 13 19:39:40 volumio systemd[1]: mpd_oled_plugin.service: Control process exited, code=exited, status=1/FAILURE Oct 13 19:39:40 volumio systemd[1]: mpd_oled_plugin.service: Failed with result 'signal'. Oct 13 19:39:40 volumio systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Oct 13 19:39:40 volumio systemd[1]: mpd_oled_plugin.service: Consumed 17.922s CPU time. Oct 13 19:39:40 volumio sudo[4967]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:39:40 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 13 19:39:40 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 13 19:39:40 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 13 19:39:40 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:40 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:40 volumio sudo[4983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:40 volumio sudo[4984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:40 volumio sudo[4984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio sudo[4983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio sudo[4984]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:40 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio vtcs[4447]: [2026-10-13 19:39:40.649] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 13 19:39:40 volumio vtcs[4447]: [2026-10-13 19:39:40.650] [tisoc] [error] [SpkconServer.cpp:476] socket write error. fd=3 errorno=32 error=Broken pipe Oct 13 19:39:40 volumio vtcs[4447]: [2026-10-13 19:39:40.650] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 13 19:39:40 volumio volumio[1524]: info: Done. Oct 13 19:39:40 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 19:39:40 volumio sudo[4994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:40 volumio sudo[4994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:40 volumio volumio[1524]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Oct 13 19:39:40 volumio volumio[1524]: error: [MPD_OLED Plugin] Could not start mpd_oled_plugin service: Error: Command failed: /usr/bin/sudo /bin/systemctl start mpd_oled_plugin.service Oct 13 19:39:40 volumio volumio[1524]: Job for mpd_oled_plugin.service canceled. Oct 13 19:39:40 volumio sudo[4998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio sudo[4998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio sudo[5003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:40 volumio sudo[5003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:39:40 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:40 volumio systemd[1]: mpd.service: Consumed 3.565s CPU time. Oct 13 19:39:40 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:39:40 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:40 volumio sudo[4994]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:40 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:40 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:40 volumio sudo[5003]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:40 volumio sudo[5020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:40 volumio sudo[5020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio sudo[5021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:40 volumio sudo[5021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:41 volumio sudo[4998]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:41 volumio qobuz-connect[4412]: 20261013 19:39:41.088 [4412.4412] INFO SampleApp: Stopping Local configuration server Oct 13 19:39:41 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:39:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:39:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:39:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:41 volumio systemd[1]: shairport-sync.service: Consumed 1.748s CPU time. Oct 13 19:39:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:41 volumio sudo[5020]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:41 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:41 volumio sudo[5026]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:41 volumio sudo[5026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:41 volumio sudo[5026]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:41 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:41 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:42 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 5 of 5. Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:42 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 3 of 5. Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio qobuz-connect[4412]: 20261013 19:39:42.587 [4412.4412] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:39:42 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:39:42 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:42 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:42 volumio sudo[5021]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:42 volumio volumio[1524]: info: Executing endpoint qc_getconfig Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.785 [5075.5075] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.792 [5075.5075] INFO VolumeManager: [0x736068]: Setting new playback volume: 75 Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.792 [5075.5075] INFO VolumeManager: [0x736068]: Setting new mute state: 0 Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.792 [5075.5075] INFO AudioStreamManager: [0x735dc0]: Setting new audio download buffer size: 1048576 Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.792 [5075.5075] INFO QobuzConnect: [0x736930]: Client initialized! Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.792 [5075.5075] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 13 19:39:42 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:39:42 volumio volumio[1524]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.826 [5075.5075] INFO LocalConfigManager: [0x735ae8]: Starting Local Configuration server Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.826 [5075.5075] INFO SampleApp: Starting Local configuration server Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.827 [5075.5075] INFO SampleApp: Connected to UNIX socket client 0x720818 Oct 13 19:39:42 volumio qobuz-connect[5075]: 20261013 19:39:42.935 [5075.5075] INFO SampleApp: Playback volume changed: 75 Oct 13 19:39:42 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:42 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:43 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:43 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:44 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:44 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:44 volumio sudo[5128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:39:44 volumio sudo[5128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:44 volumio sudo[5128]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:44 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:44 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 4 of 5. Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio mpd[5045]: 2026-10-13T19:39:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 19:39:44 volumio volumio[1524]: info: Executing endpoint tc_getconfig Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 13 19:39:44 volumio vtcs[5134]: STARTING TidalConnect services, version: 1.6.1 Oct 13 19:39:44 volumio vtcs[5134]: STARTED TidalConnect services. Oct 13 19:39:44 volumio volumio[1524]: info: Executing endpoint tc_connect Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 13 19:39:44 volumio volumio[1524]: info: Connecting to TidalConnect Oct 13 19:39:44 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 19:39:44 volumio sudo[4983]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:44 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:44 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:44 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:44 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:44 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:44 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:44 volumio volumio[1524]: error: updateQueue error: null Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:44 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:44 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio volumio[1524]: info: Enabling plugin mpd_oled Oct 13 19:39:45 volumio volumio[1524]: info: Loading plugin "mpd_oled"... Oct 13 19:39:45 volumio volumio[1524]: info: Applying required configuration parameters for plugin mpd_oled Oct 13 19:39:45 volumio volumio[1524]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Oct 13 19:39:45 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:45 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:45 volumio volumio[1524]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 13 19:39:45 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:45 volumio volumio[1524]: info: Asound.conf file written Oct 13 19:39:45 volumio sudo[5170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:39:45 volumio sudo[5170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio sudo[5170]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:39:45 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 13 19:39:45 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 13 19:39:45 volumio volumio[1524]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 13 19:39:45 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:45 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:45 volumio sudo[5180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:45 volumio sudo[5178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:45 volumio sudo[5180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio sudo[5178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio sudo[5178]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:45 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio vtcs[5134]: [2026-10-13 19:39:45.335] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 13 19:39:45 volumio vtcs[5134]: [2026-10-13 19:39:45.335] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 13 19:39:45 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 19:39:45 volumio volumio[1524]: info: PLUGIN START: mpd_oled Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 13 19:39:45 volumio sudo[5190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:45 volumio sudo[5190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: Preparing to generate the ALSA configuration file Oct 13 19:39:45 volumio sudo[5196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Oct 13 19:39:45 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:45 volumio sudo[5196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:45 volumio systemd[1]: mpd.service: Consumed 3.555s CPU time. Oct 13 19:39:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:45 volumio sudo[5196]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio sudo[5198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Oct 13 19:39:45 volumio sudo[5198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: Done. Oct 13 19:39:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:45 volumio sudo[5203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:45 volumio sudo[5203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:39:45 volumio volumio[1524]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 13 19:39:45 volumio volumio[1524]: info: Reading ALSA contributions from plugins. Oct 13 19:39:45 volumio volumio[1524]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Oct 13 19:39:45 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:45 volumio sudo[5211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:45 volumio sudo[5211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio systemd[1]: vtcs.service: Killing process 5142 (vtcs) with signal SIGKILL. Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:39:45 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:45 volumio sudo[5211]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio sudo[5190]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio volumio[1524]: info: Asound.conf file unchanged, so no further update is needed Oct 13 19:39:45 volumio volumio[1524]: info: Output device has changed, restarting MPD Oct 13 19:39:45 volumio sudo[5203]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio sudo[5221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:45 volumio sudo[5221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: Output device has changed, restarting Shairport Sync Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:45 volumio sudo[5222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:45 volumio sudo[5222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio sudo[5225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:39:45 volumio sudo[5228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:39:45 volumio sudo[5225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:39:45 volumio volumio[1524]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:39:45 volumio sudo[5228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:45 volumio sudo[5208]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:45 volumio sudo[5208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:45 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:45 volumio sudo[5225]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio sudo[5208]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:45 volumio sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:45 volumio sudo[5241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio sudo[5250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:39:45 volumio sudo[5250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: MPD Permissions set Oct 13 19:39:45 volumio sudo[5258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:39:45 volumio sudo[5258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:45 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio volumio[1524]: info: Starting Shairport Sync Oct 13 19:39:46 volumio sudo[5258]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio qobuz-connect[5075]: 20261013 19:39:46.092 [5075.5075] INFO SampleApp: Stopping Local configuration server Oct 13 19:39:46 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:39:46 volumio sudo[5266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:39:46 volumio sudo[5266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:39:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:39:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:46 volumio systemd[1]: shairport-sync.service: Consumed 1.603s CPU time. Oct 13 19:39:46 volumio sudo[5267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:39:46 volumio sudo[5267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:46 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:39:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:39:46 volumio sudo[5241]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio sudo[5250]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:39:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:39:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:39:46 volumio volumio[1524]: info: [cd-plugin] Set CD speed to 1X Oct 13 19:39:46 volumio volumio[1524]: info: [cd-plugin] Slowing down the CD drive to a whisper speed. Adjustment 5 of 5. Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:39:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:39:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:39:46 volumio sudo[5222]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:46 volumio sudo[5266]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio volumio[1524]: info: Shairport-Sync Started Oct 13 19:39:46 volumio volumio[1524]: (node:1524) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 13 19:39:46 volumio volumio[1524]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 13 19:39:46 volumio volumio[1524]: (node:1524) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 13 19:39:46 volumio volumio[1524]: (node:1524) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 13 19:39:46 volumio volumio[1524]: (node:1524) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 13 19:39:46 volumio volumio[1524]: (node:1524) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio sudo[5277]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:39:46 volumio sudo[5277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:46 volumio sudo[5277]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio qobuz-connect[5075]: 20261013 19:39:46.838 [5075.5075] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:39:46 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:39:46 volumio volumio[1524]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:46 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:46 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:39:46 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:46 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:39:46 volumio sudo[5221]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio sudo[5267]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:46 volumio volumio[1524]: info: Executing endpoint qc_getconfig Oct 13 19:39:46 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.018 [5303.5303] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.024 [5303.5303] INFO VolumeManager: [0x10d0068]: Setting new playback volume: 75 Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.024 [5303.5303] INFO VolumeManager: [0x10d0068]: Setting new mute state: 0 Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.024 [5303.5303] INFO AudioStreamManager: [0x10cfdc0]: Setting new audio download buffer size: 1048576 Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.024 [5303.5303] INFO QobuzConnect: [0x10d0930]: Client initialized! Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.024 [5303.5303] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 13 19:39:47 volumio volumio[1524]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:39:47 volumio volumio[1524]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.055 [5303.5303] INFO LocalConfigManager: [0x10cfae8]: Starting Local Configuration server Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.056 [5303.5303] INFO SampleApp: Starting Local configuration server Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.056 [5303.5303] INFO SampleApp: Connected to UNIX socket client 0x10ba818 Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio qobuz-connect[5303]: 20261013 19:39:47.168 [5303.5303] INFO SampleApp: Playback volume changed: 75 Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio volumio[1524]: info: TidalConnect service started! Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:47 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:47 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:48 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:48 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:48 volumio sudo[5349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:39:48 volumio sudo[5349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:39:48 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:48 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:48 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:39:48 volumio sudo[5349]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:49 volumio volumio[1524]: info: Executing endpoint tc_getconfig Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 13 19:39:49 volumio vtcs[5352]: STARTING TidalConnect services, version: 1.6.1 Oct 13 19:39:49 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio vtcs[5352]: STARTED TidalConnect services. Oct 13 19:39:49 volumio volumio[1524]: info: Executing endpoint tc_connect Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 13 19:39:49 volumio volumio[1524]: info: Connecting to TidalConnect Oct 13 19:39:49 volumio volumio[1524]: info: TidalConnect service stoped! Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:49 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:49 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:49 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::servicePushState Oct 13 19:39:49 volumio volumio[1524]: info: CoreStateMachine::pushState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioPushState Oct 13 19:39:49 volumio volumio[1524]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:39:49 volumio volumio[1524]: info: MRS: Pushing multiroomSync output Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 13 19:39:49 volumio volumio[1524]: info: CoreCommandRouter::volumioGetState Oct 13 19:39:49 volumio volumio[1524]: info: CorePlayQueue::getTrack 7 Oct 13 19:39:49 volumio volumio[1524]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:49 volumio volumio[1524]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:39:49 volumio volumio[1524]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 19:39:49 volumio volumio[1524]: Error: ENOENT: no such file or directory, unlink '/tmp/2yTwX6uS039RXi3PpryCaAfD6lt1iuDESkxKuACRqtDjejwkyYyf253YQkgRC5sn1ivGONKWjG4Q03CnyZPA9zXC5EEVDEbnq1BN' Oct 13 19:39:49 volumio volumio[1524]: at Object.unlinkSync (node:fs:1899:3) Oct 13 19:39:49 volumio volumio[1524]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11265) Oct 13 19:39:49 volumio volumio[1524]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Oct 13 19:39:49 volumio volumio[1524]: at TidalConnectClient.emit (node:events:514:28) Oct 13 19:39:49 volumio volumio[1524]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44713) Oct 13 19:39:49 volumio volumio[1524]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41683 Oct 13 19:39:49 volumio volumio[1524]: at Array.forEach () Oct 13 19:39:49 volumio volumio[1524]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41561) Oct 13 19:39:49 volumio volumio[1524]: at Socket.emit (node:events:514:28) Oct 13 19:39:49 volumio volumio[1524]: at addChunk (node:internal/streams/readable:343:12) Oct 13 19:39:49 volumio volumio[1524]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 13 19:39:49 volumio volumio[1524]: at Readable.push (node:internal/streams/readable:253:10) Oct 13 19:39:49 volumio volumio[1524]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Oct 13 19:39:49 volumio volumio[1524]: errno: -2, Oct 13 19:39:49 volumio volumio[1524]: syscall: 'unlink', Oct 13 19:39:49 volumio volumio[1524]: code: 'ENOENT', Oct 13 19:39:49 volumio volumio[1524]: path: '/tmp/2yTwX6uS039RXi3PpryCaAfD6lt1iuDESkxKuACRqtDjejwkyYyf253YQkgRC5sn1ivGONKWjG4Q03CnyZPA9zXC5EEVDEbnq1BN' Oct 13 19:39:49 volumio volumio[1524]: } Oct 13 19:39:49 volumio volumio[1524]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 19:39:50 volumio mpd[5301]: 2026-10-13T19:39:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 19:39:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 19:39:50 volumio sudo[5228]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:50 volumio sudo[5180]: pam_unix(sudo:session): session closed for user root Oct 13 19:39:50 volumio systemd[1]: Starting mpd_oled_plugin.service - MPD OLED Plugin... Oct 13 19:39:50 volumio sudo[5408]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/journalctl -t volumio Oct 13 19:39:50 volumio sudo[5408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:39:50 volumio sudo[5413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 19:38' Oct 13 19:39:50 volumio sudo[5413]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"