Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 22:27:00 vvaudio volumio[945]: info: Getting Alsa Cards List without I2S DAC Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:27:00 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:00 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:00 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Dec 26 22:27:00 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 22:27:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 22:27:04 vvaudio volumio[945]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 26 22:27:04 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio sudo[10179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 22:27:05 vvaudio sudo[10179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:27:05 vvaudio sudo[10181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 22:27:05 vvaudio sudo[10179]: pam_unix(sudo:session): session closed for user root Dec 26 22:27:05 vvaudio sudo[10181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:27:05 vvaudio volumio[945]: info: MPD Permissions set Dec 26 22:27:05 vvaudio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 22:27:05 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:05 vvaudio systemd[1]: mpd.service: Deactivated successfully. Dec 26 22:27:05 vvaudio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 22:27:05 vvaudio systemd[1]: mpd.service: Consumed 56.066s CPU time. Dec 26 22:27:05 vvaudio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 22:27:05 vvaudio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 22:27:05 vvaudio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 22:27:05 vvaudio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 22:27:05 vvaudio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 22:27:05 vvaudio sudo[10193]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 22:27:05 vvaudio sudo[10193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 22:27:05 vvaudio sudo[10193]: pam_unix(sudo:session): session closed for user root Dec 26 22:27:08 vvaudio mpd[10195]: 2025-12-26T22:27:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 22:27:08 vvaudio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 22:27:08 vvaudio sudo[10181]: pam_unix(sudo:session): session closed for user root Dec 26 22:27:08 vvaudio volumio[945]: error: updateQueue error: null Dec 26 22:27:11 vvaudio sudo[10208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 22:27:11 vvaudio sudo[10208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:27:11 vvaudio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 22:27:11 vvaudio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 22:27:11 vvaudio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 22:27:11 vvaudio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 22:27:11 vvaudio sudo[10208]: pam_unix(sudo:session): session closed for user root Dec 26 22:27:11 vvaudio mpd_monitor.sh[10212]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 22:27:11 vvaudio volumio[945]: info: Successfully started MPD Monitor Dec 26 22:27:12 vvaudio volumio[945]: info: CoreCommandRouter::volumioNext Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::next Dec 26 22:27:12 vvaudio volumio[945]: info: [1766759232943] ControllerQobuz::next Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::next Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:27:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:12 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:12 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:12 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:12 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::serviceStop Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:27:12 vvaudio volumio[945]: info: CoreCommandRouter::serviceStop Dec 26 22:27:12 vvaudio volumio[945]: info: [1766759232954] ControllerQobuz::stop Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:12 vvaudio volumio[945]: info: ControllerMpd::stop Dec 26 22:27:12 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:27:12 vvaudio volumio[945]: info: sendMpdCommand stop took 17 milliseconds Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:12 vvaudio volumio[945]: info: [1766759232972] ControllerQobuz::clearAddPlayTrack Dec 26 22:27:12 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:27:12 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:27:14 vvaudio volumio[945]: info: getStreamUrl took 1160 milliseconds Dec 26 22:27:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:27:14 vvaudio volumio[945]: info: sendMpdCommand stop took 1 milliseconds Dec 26 22:27:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 22:27:14 vvaudio volumio[945]: info: Dec 26 22:27:14 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:14 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:14 vvaudio volumio[945]: info: sendMpdCommand clear took 1 milliseconds Dec 26 22:27:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ" Dec 26 22:27:14 vvaudio volumio[945]: error: updateQueue error: null Dec 26 22:27:14 vvaudio volumio[945]: info: ------------------------------ 2ms Dec 26 22:27:16 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ" Dec 26 22:27:16 vvaudio volumio[945]: info: Dec 26 22:27:16 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:16 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:16 vvaudio volumio[945]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ" took 3 milliseconds Dec 26 22:27:16 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:16 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Dec 26 22:27:16 vvaudio volumio[945]: info: ------------------------------ 3ms Dec 26 22:27:16 vvaudio volumio[945]: info: sendMpdCommand play took 3 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: info: Dec 26 22:27:17 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:17 vvaudio volumio[945]: info: Dec 26 22:27:17 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand status took 6 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:17 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand status took 3 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:17 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","trackType":"qobuz"} Dec 26 22:27:17 vvaudio volumio[945]: verbose: CURRENT POSITION 1 Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:27:17 vvaudio volumio[945]: info: ------------------------------ 12ms Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:17 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","trackType":"qobuz"} Dec 26 22:27:17 vvaudio volumio[945]: verbose: CURRENT POSITION 1 Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus play Dec 26 22:27:17 vvaudio volumio[945]: info: Received an update from plugin. extracting info from payload Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:17 vvaudio volumio[945]: info: ------------------------------ 25ms Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPause Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::pause Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::servicePause Dec 26 22:27:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePause Dec 26 22:27:17 vvaudio volumio[945]: info: [1766759237887] ControllerQobuz::pause Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::pause Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand pause Dec 26 22:27:17 vvaudio volumio[945]: info: Dec 26 22:27:17 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand pause took 2 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:17 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand status took 1 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:17 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 26 22:27:17 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:17 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:17 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":678,"duration":137,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5207 Kbps","isStreaming":false,"title":"file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","trackType":"qobuz"} Dec 26 22:27:17 vvaudio volumio[945]: verbose: CURRENT POSITION 1 Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService pause Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:17 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:17 vvaudio volumio[945]: info: ------------------------------ 18ms Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::volumioPlay Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:20 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:27:20 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:20 vvaudio volumio[945]: info: [1766759240481] ControllerQobuz::resume Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:20 vvaudio volumio[945]: info: ControllerMpd::resume Dec 26 22:27:20 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Dec 26 22:27:20 vvaudio volumio[945]: info: Dec 26 22:27:20 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:20 vvaudio volumio[945]: info: sendMpdCommand play took 8 milliseconds Dec 26 22:27:20 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:20 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:20 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:20 vvaudio volumio[945]: info: sendMpdCommand status took 5 milliseconds Dec 26 22:27:20 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:20 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:20 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 22:27:20 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:20 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:20 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:20 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1017,"duration":137,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5116 Kbps","isStreaming":false,"title":"file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611700&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762833&hmac=rSZC1LELtIRq5C3YmbVZdU2LCCQ","trackType":"qobuz"} Dec 26 22:27:20 vvaudio volumio[945]: verbose: CURRENT POSITION 1 Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Dec 26 22:27:20 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:20 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:20 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:20 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:20 vvaudio volumio[945]: info: ------------------------------ 21ms Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioNext Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::next Dec 26 22:27:22 vvaudio volumio[945]: info: ControllerMpd::next Dec 26 22:27:22 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand next Dec 26 22:27:22 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:22 vvaudio volumio[945]: info: Dec 26 22:27:22 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:22 vvaudio volumio[945]: info: sendMpdCommand next took 19 milliseconds Dec 26 22:27:22 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:22 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:22 vvaudio volumio[945]: info: sendMpdCommand status took 1 milliseconds Dec 26 22:27:22 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:22 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 1 Dec 26 22:27:22 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 26 22:27:22 vvaudio volumio[945]: verbose: CURRENT POSITION 1 Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService stop Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus play Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:22 vvaudio volumio[945]: info: [1766759242578] ControllerQobuz::clearAddPlayTrack Dec 26 22:27:22 vvaudio volumio[945]: info: ------------------------------ 25ms Dec 26 22:27:22 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:22 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:22 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:22 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:23 vvaudio volumio[945]: info: getStreamUrl took 1032 milliseconds Dec 26 22:27:23 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:27:23 vvaudio volumio[945]: info: sendMpdCommand stop took 1 milliseconds Dec 26 22:27:23 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 22:27:23 vvaudio volumio[945]: info: Dec 26 22:27:23 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:23 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:23 vvaudio volumio[945]: info: sendMpdCommand clear took 1 milliseconds Dec 26 22:27:23 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo" Dec 26 22:27:23 vvaudio volumio[945]: error: updateQueue error: null Dec 26 22:27:23 vvaudio volumio[945]: info: ------------------------------ 3ms Dec 26 22:27:25 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo" Dec 26 22:27:25 vvaudio volumio[945]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo" took 1 milliseconds Dec 26 22:27:25 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:25 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Dec 26 22:27:25 vvaudio volumio[945]: info: Dec 26 22:27:25 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:25 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:25 vvaudio volumio[945]: info: sendMpdCommand play took 3 milliseconds Dec 26 22:27:25 vvaudio volumio[945]: info: ------------------------------ 2ms Dec 26 22:27:26 vvaudio volumio[945]: info: Dec 26 22:27:26 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:26 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:26 vvaudio volumio[945]: info: Dec 26 22:27:26 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:26 vvaudio volumio[945]: info: sendMpdCommand status took 5 milliseconds Dec 26 22:27:26 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:26 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:26 vvaudio volumio[945]: info: sendMpdCommand status took 3 milliseconds Dec 26 22:27:26 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:26 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:26 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:26 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":441,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","trackType":"qobuz"} Dec 26 22:27:26 vvaudio volumio[945]: verbose: CURRENT POSITION 2 Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:27:26 vvaudio volumio[945]: info: ------------------------------ 11ms Dec 26 22:27:26 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 22:27:26 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:26 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:26 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:26 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":441,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","trackType":"qobuz"} Dec 26 22:27:26 vvaudio volumio[945]: verbose: CURRENT POSITION 2 Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus play Dec 26 22:27:26 vvaudio volumio[945]: info: Received an update from plugin. extracting info from payload Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:26 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:26 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:26 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:26 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:26 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:26 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:26 vvaudio volumio[945]: info: ------------------------------ 23ms Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::volumioPause Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::pause Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::servicePause Dec 26 22:27:33 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::servicePause Dec 26 22:27:33 vvaudio volumio[945]: info: [1766759253643] ControllerQobuz::pause Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:33 vvaudio volumio[945]: info: ControllerMpd::pause Dec 26 22:27:33 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand pause Dec 26 22:27:33 vvaudio volumio[945]: info: Dec 26 22:27:33 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:33 vvaudio volumio[945]: info: sendMpdCommand pause took 2 milliseconds Dec 26 22:27:33 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:33 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:33 vvaudio volumio[945]: info: sendMpdCommand status took 1 milliseconds Dec 26 22:27:33 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:33 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:33 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 26 22:27:33 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:33 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:33 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:33 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2780,"duration":441,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5189 Kbps","isStreaming":false,"title":"file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","trackType":"qobuz"} Dec 26 22:27:33 vvaudio volumio[945]: verbose: CURRENT POSITION 2 Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService pause Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:33 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:27:33 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:33 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:33 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:33 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:33 vvaudio volumio[945]: info: ------------------------------ 14ms Dec 26 22:27:38 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 26 22:27:38 vvaudio volumio[945]: info: browseTIDALUri took 872 milliseconds Dec 26 22:27:39 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:39 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:44 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:27:45 vvaudio volumio[945]: info: handleBrowseUri took 946 milliseconds Dec 26 22:27:45 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:45 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:47 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:27:49 vvaudio volumio[945]: info: handleBrowseUri took 2146 milliseconds Dec 26 22:27:49 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:51 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:27:52 vvaudio volumio[945]: info: handleBrowseUri took 1175 milliseconds Dec 26 22:27:53 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:54 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:27:55 vvaudio volumio[945]: info: handleBrowseUri took 801 milliseconds Dec 26 22:27:55 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828903 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828904 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828905 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828906 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828907 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828908 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828909 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828910 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828911 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828912 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828913 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828914 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828915 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828916 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828917 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828918 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828919 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828920 Dec 26 22:27:55 vvaudio volumio[945]: info: Preloading song: qobuz://song/364828921 Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828903 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828904 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828905 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828906 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828907 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828908 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828909 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:27:55 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:27:55 vvaudio volumio[945]: error: Failed request for metavolumio API Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828910 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: explodeUri took 170 milliseconds Dec 26 22:27:55 vvaudio volumio[945]: error: QOBUZ Browse Error: No response received Dec 26 22:27:55 vvaudio volumio[945]: error: Commandrouter: Cannot explode uri qobuz://song/364828907 from service qobuz: No response received Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828911 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828912 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:55 vvaudio volumio[945]: error: Failed request for metavolumio API Dec 26 22:27:55 vvaudio volumio[945]: info: explodeUri took 483 milliseconds Dec 26 22:27:55 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828913 in service qobuz Dec 26 22:27:55 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828914 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828915 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828916 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828917 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828918 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828919 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828920 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 232 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: error: QOBUZ Browse Error: No response received Dec 26 22:27:56 vvaudio volumio[945]: error: Commandrouter: Cannot explode uri qobuz://song/364828916 from service qobuz: No response received Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 182 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: error: QOBUZ Browse Error: No response received Dec 26 22:27:56 vvaudio volumio[945]: error: Commandrouter: Cannot explode uri qobuz://song/364828917 from service qobuz: No response received Dec 26 22:27:56 vvaudio volumio[945]: info: Exploding uri qobuz://song/364828921 in service qobuz Dec 26 22:27:56 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 203 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: error: QOBUZ Browse Error: No response received Dec 26 22:27:56 vvaudio volumio[945]: error: Commandrouter: Cannot explode uri qobuz://song/364828918 from service qobuz: No response received Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 521 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 1112 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 1146 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 1099 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 1166 milliseconds Dec 26 22:27:56 vvaudio volumio[945]: info: explodeUri took 1470 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1267 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1364 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1347 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1261 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1353 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::ClearQueue Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::serviceStop Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::serviceStop Dec 26 22:27:57 vvaudio volumio[945]: info: [1766759277531] ControllerQobuz::stop Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:57 vvaudio volumio[945]: info: ControllerMpd::stop Dec 26 22:27:57 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::clearPlayQueue Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::addQueueItems Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::addQueueItems Dec 26 22:27:57 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:27:57 vvaudio volumio[945]: info: Adding Item to queue: qobuz://album/zgb21uksr9phb Dec 26 22:27:57 vvaudio volumio[945]: info: Exploding uri qobuz://album/zgb21uksr9phb in service qobuz Dec 26 22:27:57 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:27:57 vvaudio volumio[945]: info: Dec 26 22:27:57 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:27:57 vvaudio volumio[945]: info: sendMpdCommand stop took 26 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:27:57 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:27:57 vvaudio volumio[945]: info: sendMpdCommand status took 1 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:27:57 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:27:57 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:27:57 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:57 vvaudio volumio[945]: info: CorePlayQueue::getTrack 2 Dec 26 22:27:57 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611701&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762843&hmac=W5s62AcPJI_Se27i2hGSF2DtlEo","trackType":"qobuz"} Dec 26 22:27:57 vvaudio volumio[945]: verbose: CURRENT POSITION 2 Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService stop Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:57 vvaudio volumio[945]: info: No code Dec 26 22:27:57 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:27:57 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:27:57 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:57 vvaudio volumio[945]: info: ------------------------------ 31ms Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1360 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1523 milliseconds Dec 26 22:27:57 vvaudio volumio[945]: info: explodeUri took 1701 milliseconds Dec 26 22:27:58 vvaudio volumio[945]: info: explodeUri took 481 milliseconds Dec 26 22:27:58 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:27:58 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:27:58 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:27:58 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:27:58 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 22:27:58 vvaudio volumio[945]: info: CoreCommandRouter::volumioPlay Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::play index 0 Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:27:58 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:27:58 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:27:58 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:27:58 vvaudio volumio[945]: info: [1766759278028] ControllerQobuz::clearAddPlayTrack Dec 26 22:27:58 vvaudio volumio[945]: info: getStreamUrl took 491 milliseconds Dec 26 22:27:58 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:27:58 vvaudio volumio[945]: info: sendMpdCommand stop took 1 milliseconds Dec 26 22:27:58 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 22:27:58 vvaudio volumio[945]: info: Dec 26 22:27:58 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:58 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:58 vvaudio volumio[945]: info: sendMpdCommand clear took 1 milliseconds Dec 26 22:27:58 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M" Dec 26 22:27:58 vvaudio volumio[945]: error: updateQueue error: null Dec 26 22:27:58 vvaudio volumio[945]: info: ------------------------------ 2ms Dec 26 22:27:59 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M" Dec 26 22:27:59 vvaudio volumio[945]: info: Dec 26 22:27:59 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:27:59 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:27:59 vvaudio volumio[945]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M" took 2 milliseconds Dec 26 22:27:59 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:27:59 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Dec 26 22:27:59 vvaudio volumio[945]: info: ------------------------------ 3ms Dec 26 22:27:59 vvaudio volumio[945]: info: sendMpdCommand play took 2 milliseconds Dec 26 22:28:00 vvaudio volumio[945]: info: Dec 26 22:28:00 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:00 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:00 vvaudio volumio[945]: info: Dec 26 22:28:00 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:00 vvaudio volumio[945]: info: sendMpdCommand status took 5 milliseconds Dec 26 22:28:00 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:00 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:00 vvaudio volumio[945]: info: sendMpdCommand status took 2 milliseconds Dec 26 22:28:00 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:00 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:00 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:00 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":509,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M","trackType":"qobuz"} Dec 26 22:28:00 vvaudio volumio[945]: verbose: CURRENT POSITION 0 Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:28:00 vvaudio volumio[945]: info: ------------------------------ 11ms Dec 26 22:28:00 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 22:28:00 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:00 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:00 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:00 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":509,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=364828903&fmt=7&profile=raw&app_id=539451548&cid=3619370&etsp=1766762878&hmac=DWFOYbbetrYVz6Hf9j8mQPuzc1M","trackType":"qobuz"} Dec 26 22:28:00 vvaudio volumio[945]: verbose: CURRENT POSITION 0 Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus play Dec 26 22:28:00 vvaudio volumio[945]: info: Received an update from plugin. extracting info from payload Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:00 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:00 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:00 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:00 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:00 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:00 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:00 vvaudio volumio[945]: info: ------------------------------ 22ms Dec 26 22:28:10 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:28:12 vvaudio volumio[945]: info: handleBrowseUri took 1929 milliseconds Dec 26 22:28:12 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:12 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:12 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:13 vvaudio volumio[945]: error: Failed request for metavolumio API Dec 26 22:28:13 vvaudio volumio[945]: error: Failed request for metavolumio API Dec 26 22:28:14 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::ClearQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:14 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:14 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::serviceStop Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::serviceStop Dec 26 22:28:14 vvaudio volumio[945]: info: [1766759294314] ControllerQobuz::stop Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:28:14 vvaudio volumio[945]: info: ControllerMpd::stop Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::clearPlayQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::addQueueItems Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::addQueueItems Dec 26 22:28:14 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611699 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611699 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611700 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611700 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611701 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611701 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611702 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611702 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611703 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611703 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPlay Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::play index 4 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::addQueueItems Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::addQueueItems Dec 26 22:28:14 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611704 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611704 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611705 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611705 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611706 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611706 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611707 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611707 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611708 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611708 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611709 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611709 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611710 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611710 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611711 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611711 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611712 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611712 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611713 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611713 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611714 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611714 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611715 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611715 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611716 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611716 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611717 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611717 Dec 26 22:28:14 vvaudio volumio[945]: info: Adding Item to queue: qobuz://song/366611718 Dec 26 22:28:14 vvaudio volumio[945]: info: Using cached record of: qobuz://song/366611718 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:14 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:14 vvaudio volumio[945]: info: [1766759294347] ControllerQobuz::clearAddPlayTrack Dec 26 22:28:14 vvaudio volumio[945]: info: Dec 26 22:28:14 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:14 vvaudio volumio[945]: info: sendMpdCommand stop took 52 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:14 vvaudio volumio[945]: info: sendMpdCommand status took 2 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:14 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:14 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:14 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:14 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:14 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:14 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:14 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:14 vvaudio volumio[945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Dec 26 22:28:14 vvaudio volumio[945]: info: ------------------------------ 22ms Dec 26 22:28:14 vvaudio volumio[945]: info: getStreamUrl took 484 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:28:14 vvaudio volumio[945]: info: sendMpdCommand stop took 1 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 22:28:14 vvaudio volumio[945]: info: Dec 26 22:28:14 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:28:14 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:28:14 vvaudio volumio[945]: info: sendMpdCommand clear took 2 milliseconds Dec 26 22:28:14 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY" Dec 26 22:28:14 vvaudio volumio[945]: error: updateQueue error: null Dec 26 22:28:14 vvaudio volumio[945]: info: ------------------------------ 4ms Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY" Dec 26 22:28:17 vvaudio volumio[945]: info: Dec 26 22:28:17 vvaudio volumio[945]: ---------------------------- MPD announces system playlist update Dec 26 22:28:17 vvaudio volumio[945]: info: Ignoring MPD Status Update Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY" took 4 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Dec 26 22:28:17 vvaudio volumio[945]: info: ------------------------------ 5ms Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand play took 3 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: info: Dec 26 22:28:17 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:17 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:17 vvaudio volumio[945]: info: Dec 26 22:28:17 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:17 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand status took 2 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand status took 2 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:17 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:17 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:17 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","trackType":"qobuz"} Dec 26 22:28:17 vvaudio volumio[945]: verbose: CURRENT POSITION 4 Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:28:17 vvaudio volumio[945]: info: ------------------------------ 12ms Dec 26 22:28:17 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 26 22:28:17 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:17 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:17 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:17 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","trackType":"qobuz"} Dec 26 22:28:17 vvaudio volumio[945]: verbose: CURRENT POSITION 4 Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService play Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus play Dec 26 22:28:17 vvaudio volumio[945]: info: Received an update from plugin. extracting info from payload Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:17 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:17 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:17 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:17 vvaudio volumio[945]: info: ------------------------------ 35ms Dec 26 22:28:41 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 26 22:28:43 vvaudio volumio[945]: info: handleBrowseUri took 1530 milliseconds Dec 26 22:28:43 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168229 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168230 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168231 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168232 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168233 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168234 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168235 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168236 Dec 26 22:28:43 vvaudio volumio[945]: info: Preloading song: qobuz://song/348168237 Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168229 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168230 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168231 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint getSimilarAlbums Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168232 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168233 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168234 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Executing endpoint metavolumio Dec 26 22:28:43 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168235 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168236 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: Exploding uri qobuz://song/348168237 in service qobuz Dec 26 22:28:43 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:43 vvaudio volumio[945]: info: explodeUri took 474 milliseconds Dec 26 22:28:43 vvaudio volumio[945]: info: explodeUri took 157 milliseconds Dec 26 22:28:43 vvaudio volumio[945]: error: QOBUZ Browse Error: No response received Dec 26 22:28:43 vvaudio volumio[945]: error: Commandrouter: Cannot explode uri qobuz://song/348168236 from service qobuz: No response received Dec 26 22:28:44 vvaudio volumio[945]: info: explodeUri took 1181 milliseconds Dec 26 22:28:44 vvaudio volumio[945]: info: explodeUri took 1166 milliseconds Dec 26 22:28:44 vvaudio volumio[945]: info: explodeUri took 1119 milliseconds Dec 26 22:28:44 vvaudio volumio[945]: info: explodeUri took 1090 milliseconds Dec 26 22:28:44 vvaudio volumio[945]: info: explodeUri took 1158 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::ClearQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::stPlaybackTimer Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::serviceStop Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::serviceStop Dec 26 22:28:45 vvaudio volumio[945]: info: [1766759325041] ControllerQobuz::stop Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 22:28:45 vvaudio volumio[945]: info: ControllerMpd::stop Dec 26 22:28:45 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::clearPlayQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::addQueueItems Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::addQueueItems Dec 26 22:28:45 vvaudio volumio[945]: info: Preload queue cleared Dec 26 22:28:45 vvaudio volumio[945]: info: Adding Item to queue: qobuz://album/v3v4t2iy4vaza Dec 26 22:28:45 vvaudio volumio[945]: info: Exploding uri qobuz://album/v3v4t2iy4vaza in service qobuz Dec 26 22:28:45 vvaudio volumio[945]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 26 22:28:45 vvaudio volumio[945]: info: explodeUri took 1230 milliseconds Dec 26 22:28:45 vvaudio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 26 22:28:45 vvaudio volumio[945]: info: Dec 26 22:28:45 vvaudio volumio[945]: ---------------------------- MPD announces state update: player Dec 26 22:28:45 vvaudio volumio[945]: info: sendMpdCommand stop took 34 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: info: ControllerMpd::getState Dec 26 22:28:45 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Dec 26 22:28:45 vvaudio volumio[945]: info: sendMpdCommand status took 2 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: verbose: ControllerMpd::parseState Dec 26 22:28:45 vvaudio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 22:28:45 vvaudio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: verbose: ControllerMpd::parseTrackInfo Dec 26 22:28:45 vvaudio volumio[945]: info: ControllerMpd::pushState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::servicePushState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 4 Dec 26 22:28:45 vvaudio volumio[945]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9395854&eid=366611703&fmt=27&profile=raw&app_id=539451548&cid=3619370&etsp=1766762894&hmac=XuNN6QoFs1J_7k4oC6eHmb2bPAY","trackType":"qobuz"} Dec 26 22:28:45 vvaudio volumio[945]: verbose: CURRENT POSITION 4 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::syncState stateService stop Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:45 vvaudio volumio[945]: info: No code Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::pushState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushState Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output update for this device Dec 26 22:28:45 vvaudio volumio[945]: info: MRS: Pushing multiroomSync output Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:45 vvaudio volumio[945]: info: ------------------------------ 36ms Dec 26 22:28:45 vvaudio volumio[945]: info: explodeUri took 1620 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: info: explodeUri took 401 milliseconds Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPushQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::saveQueue Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::updateTrackBlock Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrackBlock Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioGetState Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 22:28:45 vvaudio volumio[945]: info: CoreCommandRouter::volumioPlay Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::play index 0 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::stop Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::play index undefined Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:45 vvaudio volumio[945]: info: CoreStateMachine::startPlaybackTimer Dec 26 22:28:45 vvaudio volumio[945]: info: CorePlayQueue::getTrack 0 Dec 26 22:28:45 vvaudio volumio[945]: info: [1766759325455] ControllerQobuz::clearAddPlayTrack Dec 26 22:28:45 vvaudio volumio[945]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Franz%20Liszt/Piano%20Works/d1ea8f8d-2253-4b57-ad70-c163947ad1e3.jpg' Dec 26 22:28:45 vvaudio volumio[945]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fr%C3%A9d%C3%A9ric%20Chopin/Nocturnes/1de887d1-8ed6-454c-8b4a-5e0cbfa0ef34.jpg' Dec 26 22:28:45 vvaudio volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 22:28:45 vvaudio volumio[945]: Error: Client network socket disconnected before secure TLS connection was established Dec 26 22:28:45 vvaudio volumio[945]: at connResetException (node:internal/errors:720:14) Dec 26 22:28:45 vvaudio volumio[945]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Dec 26 22:28:45 vvaudio volumio[945]: at TLSSocket.emit (node:events:526:35) Dec 26 22:28:45 vvaudio volumio[945]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 26 22:28:45 vvaudio volumio[945]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 26 22:28:45 vvaudio volumio[945]: code: 'ECONNRESET', Dec 26 22:28:45 vvaudio volumio[945]: path: null, Dec 26 22:28:45 vvaudio volumio[945]: host: 'lastfm.freetls.fastly.net', Dec 26 22:28:45 vvaudio volumio[945]: port: 443, Dec 26 22:28:45 vvaudio volumio[945]: localAddress: undefined Dec 26 22:28:45 vvaudio volumio[945]: } Dec 26 22:28:45 vvaudio volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 22:28:46 vvaudio sudo[10568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 22:27' Dec 26 22:28:46 vvaudio sudo[10568]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"