-- Logs begin at Fri 2026-02-27 11:38:48 CET, end at Sun 2026-05-31 22:12:46 CEST. -- May 31 22:11:00 rivo sudo[7237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 22:11:00 rivo sudo[7237]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 22:11:00 rivo sudo[7237]: pam_unix(sudo:session): session closed for user root May 31 22:11:00 rivo volumio[3188]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 31 22:11:00 rivo sudo[7247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 22:11:00 rivo sudo[7247]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 22:11:00 rivo sudo[7247]: pam_unix(sudo:session): session closed for user root May 31 22:11:00 rivo volumio[3188]: info: MPD Permissions set May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo sudo[7250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo sudo[7250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 22:11:00 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 22:11:00 rivo systemd[1]: mpd.service: Succeeded. May 31 22:11:00 rivo systemd[1]: Stopped Music Player Daemon. May 31 22:11:00 rivo systemd[1]: Starting Music Player Daemon... May 31 22:11:00 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 22:11:00 rivo volumio[3188]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 22:11:00 rivo volumio[3188]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 22:11:00 rivo volumio[3188]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 22:11:00 rivo volumio[3188]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 22:11:00 rivo sudo[7258]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 22:11:00 rivo sudo[7258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 22:11:01 rivo sudo[7258]: pam_unix(sudo:session): session closed for user root May 31 22:11:02 rivo mpd[7260]: May 31 22:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 22:11:02 rivo systemd[1]: Started Music Player Daemon. May 31 22:11:02 rivo sudo[7229]: pam_unix(sudo:session): session closed for user root May 31 22:11:02 rivo sudo[7250]: pam_unix(sudo:session): session closed for user root May 31 22:11:03 rivo volumio[3188]: error: MPD error: The expression evaluated to a falsy value: May 31 22:11:03 rivo volumio[3188]: assert.ok(self.idling) May 31 22:11:03 rivo volumio[3188]: error: The expression evaluated to a falsy value: May 31 22:11:03 rivo volumio[3188]: assert.ok(self.idling) May 31 22:11:03 rivo volumio[3188]: error: updateQueue error: null May 31 22:11:03 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:03 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:07 rivo volumio[3188]: info: CoreCommandRouter::volumioPlay May 31 22:11:07 rivo volumio[3188]: info: CoreStateMachine::play index undefined May 31 22:11:07 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:11:07 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:10 rivo kernel: hdmitx: system: irq 4 0 May 31 22:11:10 rivo kernel: hdmitx: system: plugout May 31 22:11:10 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:11:10 rivo kernel: hdmitx: system: irq 2 0 May 31 22:11:10 rivo kernel: hdmitx: system: plugin May 31 22:11:10 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:11:10 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:10 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:10 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:10 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:10 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:11:10 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:11:10 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:11:10 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:10 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:10 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:11:10 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:10 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:10 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:11:10 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:11:10 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:11:10 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:11:10 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:10 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:11:10 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:11:10 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:10 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:11:10 rivo kernel: hdmitx: hw: div40: 0 May 31 22:11:10 rivo kernel: hdmitx: frac_rate = 1 May 31 22:11:10 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:11:10 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:11:10 rivo kernel: hdmitx: config HPLL done May 31 22:11:10 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:11:10 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:11:10 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:11:10 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:11 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:11 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:11 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:11 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:11 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:11 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:11 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:11 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:11 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:11:11 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:11:11 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:11:11 rivo kernel: hdmitx: hw: set audio May 31 22:11:11 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:11 rivo kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 May 31 22:11:11 rivo kernel: hdmitx: hw: aud_n_para = 8918 May 31 22:11:11 rivo kernel: hdmitx: hw: set channel status May 31 22:11:11 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:11 rivo kernel: hdmitx: hw: set audio May 31 22:11:11 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:11 rivo kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 May 31 22:11:11 rivo kernel: hdmitx: hw: aud_n_para = 8918 May 31 22:11:11 rivo kernel: hdmitx: hw: set channel status May 31 22:11:11 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:12 rivo systemd[1]: Starting Cleanup of Temporary Directories... May 31 22:11:12 rivo volumio[3188]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 22:11:12 rivo systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 31 22:11:12 rivo systemd[1]: Started Cleanup of Temporary Directories. May 31 22:11:17 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 31 22:11:17 rivo volumio[3188]: info: browseTIDALUri took 294 milliseconds May 31 22:11:17 rivo volumio[3188]: info: Preload queue cleared May 31 22:11:17 rivo volumio[3188]: info: Preload queue cleared May 31 22:11:22 rivo kernel: hdmitx: system: irq 4 0 May 31 22:11:22 rivo kernel: hdmitx: system: plugout May 31 22:11:22 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:11:22 rivo kernel: hdmitx: system: irq 2 0 May 31 22:11:22 rivo kernel: hdmitx: system: plugin May 31 22:11:23 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:11:23 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:23 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:23 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:23 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:23 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:11:23 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:11:23 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:11:23 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:23 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:23 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:11:23 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:23 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:23 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:11:23 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:11:23 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:11:23 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:11:23 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:23 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:11:23 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:11:23 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:23 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:11:23 rivo kernel: hdmitx: hw: div40: 0 May 31 22:11:23 rivo kernel: hdmitx: frac_rate = 1 May 31 22:11:23 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:11:23 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:11:23 rivo kernel: hdmitx: config HPLL done May 31 22:11:23 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:11:23 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:11:23 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:11:23 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:23 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:23 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:23 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:23 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:23 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:23 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:23 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:23 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:23 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:11:23 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:11:23 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:11:23 rivo kernel: hdmitx: hw: set audio May 31 22:11:23 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:23 rivo kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 May 31 22:11:23 rivo kernel: hdmitx: hw: aud_n_para = 8918 May 31 22:11:23 rivo kernel: hdmitx: hw: set channel status May 31 22:11:23 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:23 rivo kernel: hdmitx: hw: set audio May 31 22:11:23 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:23 rivo kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 May 31 22:11:23 rivo kernel: hdmitx: hw: aud_n_para = 8918 May 31 22:11:23 rivo kernel: hdmitx: hw: set channel status May 31 22:11:23 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:25 rivo volumio[3188]: Searching plugin music_service/tidal May 31 22:11:25 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 31 22:11:25 rivo volumio[3188]: info: searchTIDALUri took 728 milliseconds May 31 22:11:25 rivo volumio[3188]: info: search took 731 milliseconds May 31 22:11:25 rivo volumio[3188]: info: All search sources collected, pushing search results May 31 22:11:29 rivo volumio[3188]: info: Preload queue cleared May 31 22:11:29 rivo volumio[3188]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 22:11:29 rivo volumio[3188]: info: CoreStateMachine::ClearQueue May 31 22:11:29 rivo volumio[3188]: info: CoreStateMachine::stop May 31 22:11:29 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:11:29 rivo volumio[3188]: info: CorePlayQueue::clearPlayQueue May 31 22:11:29 rivo volumio[3188]: info: CorePlayQueue::saveQueue May 31 22:11:29 rivo volumio[3188]: info: CoreCommandRouter::volumioPushQueue May 31 22:11:29 rivo volumio[3188]: info: CoreStateMachine::addQueueItems May 31 22:11:29 rivo volumio[3188]: info: CorePlayQueue::addQueueItems May 31 22:11:29 rivo volumio[3188]: info: Preload queue cleared May 31 22:11:29 rivo volumio[3188]: info: Adding Item to queue: tidal://artist/36978 May 31 22:11:29 rivo volumio[3188]: info: Exploding uri tidal://artist/36978 in service tidal May 31 22:11:31 rivo volumio[3188]: info: explodeTIDALUri took 1495 milliseconds May 31 22:11:31 rivo volumio[3188]: info: CoreCommandRouter::volumioPushQueue May 31 22:11:31 rivo volumio[3188]: info: CorePlayQueue::saveQueue May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::updateTrackBlock May 31 22:11:31 rivo volumio[3188]: info: CorePlayQueue::getTrackBlock May 31 22:11:31 rivo volumio[3188]: info: CoreCommandRouter::volumioPlay May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::play index 0 May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::stop May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::play index undefined May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:11:31 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::startPlaybackTimer May 31 22:11:31 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:31 rivo volumio[3188]: info: [1780258291127] ControllerTidal::clearAddPlayTrack May 31 22:11:31 rivo volumio[3188]: info: Getting stream with soundQuality HI_RES May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand stop May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand stop took 17 milliseconds May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand clear May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:11:31 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand clear took 4 milliseconds May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES" May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:11:31 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:11:31 rivo volumio[3188]: error: updateQueue error: null May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:11:31 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:11:31 rivo volumio[3188]: info: ------------------------------ 13ms May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES" took 7 milliseconds May 31 22:11:31 rivo volumio[3188]: info: ------------------------------ 7ms May 31 22:11:31 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand play May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:11:31 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:11:31 rivo volumio[3188]: info: ------------------------------ 14ms May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand play took 8 milliseconds May 31 22:11:31 rivo volumio[3188]: info: ------------------------------ 8ms May 31 22:11:31 rivo volumio[3188]: STREAMING PROXY: Handling url /?data=tidal://song/65080757&soundQuality=HI_RES May 31 22:11:31 rivo volumio[3188]: info: Executing endpoint getStreamUrltidal May 31 22:11:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 31 22:11:31 rivo volumio[3188]: info: getStreamUrl took 315 milliseconds May 31 22:11:31 rivo volumio[3188]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczYmY3OWUwMGMyODhjOGYwZGZjNjBjYTJiYzIxZjBkY182MS5tcDQ/0.flac?token=1780261891~NDhhMjk0NDc0OTg5YjBkYTlkM2Y1NjMyOTJiYjQxOTczMDQ5MDk3MQ== May 31 22:11:31 rivo volumio[3188]: STREAMING PROXY: Requesting upstream with range: none May 31 22:11:31 rivo volumio[3188]: STREAMING PROXY: Upstream status code: 200 May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:11:31 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:11:31 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:11:31 rivo kernel: aml_spdif_open May 31 22:11:31 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 May 31 22:11:31 rivo kernel: set normal 512 fs /4 fs May 31 22:11:31 rivo kernel: set spdifout clk:24576000, mpll:98304000 May 31 22:11:31 rivo kernel: get spdifout clk:24575951, mpll:98303801 May 31 22:11:31 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 May 31 22:11:31 rivo kernel: set normal 512 fs /4 fs May 31 22:11:31 rivo kernel: set spdifout clk:24576000, mpll:98304000 May 31 22:11:31 rivo kernel: get spdifout clk:24575951, mpll:98303801 May 31 22:11:31 rivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 May 31 22:11:31 rivo kernel: hdmitx: hw: set audio May 31 22:11:31 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:31 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:31 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:31 rivo kernel: hdmitx: hw: set channel status May 31 22:11:31 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:31 rivo kernel: hdmitx: audio: set audio param May 31 22:11:31 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 May 31 22:11:31 rivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 May 31 22:11:31 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand status took 41 milliseconds May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:11:31 rivo volumio[3188]: info: May 31 22:11:31 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:11:31 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:11:31 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand status took 54 milliseconds May 31 22:11:31 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 12 milliseconds May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:11:32 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:11:32 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:32 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:11:32 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus stop May 31 22:11:32 rivo volumio[3188]: info: ------------------------------ 72ms May 31 22:11:32 rivo volumio[3188]: info: May 31 22:11:32 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:11:32 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:11:32 rivo volumio[3188]: info: sendMpdCommand status took 18 milliseconds May 31 22:11:32 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 12 milliseconds May 31 22:11:32 rivo volumio[3188]: info: sendMpdCommand status took 3 milliseconds May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:11:32 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:11:32 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:32 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:11:32 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:11:32 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable May 31 22:11:32 rivo volumio[3188]: info: ------------------------------ 264ms May 31 22:11:32 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 201 milliseconds May 31 22:11:32 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 200 milliseconds May 31 22:11:32 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:11:32 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:11:32 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:32 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1121 Kbps","isStreaming":false,"title":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:11:32 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:11:32 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:11:32 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:11:32 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1121 Kbps","isStreaming":false,"title":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:11:32 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:11:32 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:11:32 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:11:32 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:11:32 rivo volumio[3188]: info: ------------------------------ 468ms May 31 22:11:32 rivo volumio[3188]: info: ------------------------------ 449ms May 31 22:11:32 rivo volumio[3188]: info: Signalling Playback active due to playback status change May 31 22:11:32 rivo volumio[3188]: info: MCU Signalled Playback Active May 31 22:11:34 rivo volumio[3188]: STREAMING PROXY: Upstream closed. Received 35550628 bytes May 31 22:11:34 rivo volumio[3188]: STREAMING PROXY: Client closed connection May 31 22:11:34 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:11:34 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:11:34 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:11:34 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:11:34 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:11:34 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:11:34 rivo kernel: hdmitx: system: irq 4 0 May 31 22:11:34 rivo kernel: hdmitx: system: plugout May 31 22:11:34 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:11:34 rivo kernel: hdmitx: system: irq 2 0 May 31 22:11:35 rivo kernel: hdmitx: system: plugin May 31 22:11:35 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:11:35 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:35 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:35 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:35 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:35 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:11:35 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:11:35 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:11:35 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:35 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:35 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:11:35 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:35 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:35 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:11:35 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:11:35 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:11:35 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:11:35 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:35 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:11:35 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:11:35 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:35 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:11:35 rivo kernel: hdmitx: hw: div40: 0 May 31 22:11:35 rivo kernel: hdmitx: frac_rate = 1 May 31 22:11:35 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:11:35 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:11:35 rivo kernel: hdmitx: config HPLL done May 31 22:11:35 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:11:35 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:11:35 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:11:35 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:35 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:35 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:35 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:35 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:35 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:35 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:11:35 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:11:35 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:11:35 rivo kernel: hdmitx: hw: set audio May 31 22:11:35 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:35 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:35 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:35 rivo kernel: hdmitx: hw: set channel status May 31 22:11:35 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:35 rivo kernel: hdmitx: hw: set audio May 31 22:11:35 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:35 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:35 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:35 rivo kernel: hdmitx: hw: set channel status May 31 22:11:35 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:35 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:35 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:11:35 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:35 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:11:35 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:35 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:35 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:35 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:35 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:35 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:11:46 rivo kernel: hdmitx: system: irq 80000004 0 May 31 22:11:46 rivo kernel: hdmitx: system: plugout May 31 22:11:46 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:11:46 rivo kernel: hdmitx: system: irq 80000002 0 May 31 22:11:47 rivo kernel: hdmitx: system: plugin May 31 22:11:47 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:11:47 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:47 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:47 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:47 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:47 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:11:47 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:11:47 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:11:47 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:47 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:47 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:11:47 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:47 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:47 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:11:47 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:11:47 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:11:47 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:11:47 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:47 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:11:47 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:11:47 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:47 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:11:47 rivo kernel: hdmitx: hw: div40: 0 May 31 22:11:47 rivo kernel: hdmitx: frac_rate = 1 May 31 22:11:47 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:11:47 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:11:47 rivo kernel: hdmitx: config HPLL done May 31 22:11:47 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:11:47 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:11:47 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:11:47 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:11:47 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:11:47 rivo kernel: hdmitx: hw: set audio May 31 22:11:47 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:47 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:47 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:47 rivo kernel: hdmitx: hw: set channel status May 31 22:11:47 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:47 rivo kernel: hdmitx: hw: set audio May 31 22:11:47 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:47 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:47 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:47 rivo kernel: hdmitx: hw: set channel status May 31 22:11:47 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:47 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:47 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:11:47 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:47 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:11:47 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:47 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:47 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:47 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:47 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:47 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:47 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:47 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:11:49 rivo nmbd[3072]: [2026/05/31 22:11:49.399099, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 31 22:11:49 rivo nmbd[3072]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 31 22:11:54 rivo ntpd[5572]: 89.161.47.132 local addr 192.168.0.73 -> May 31 22:11:59 rivo kernel: hdmitx: system: irq 80000004 0 May 31 22:11:59 rivo kernel: hdmitx: system: plugout May 31 22:11:59 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:11:59 rivo kernel: hdmitx: system: irq 80000002 0 May 31 22:11:59 rivo kernel: hdmitx: system: plugin May 31 22:11:59 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:11:59 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:59 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:59 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:59 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:11:59 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:11:59 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:11:59 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:11:59 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:59 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:59 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:11:59 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:59 rivo kernel: hdmitx: edid: check sum valid May 31 22:11:59 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:11:59 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:11:59 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:11:59 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:11:59 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:59 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:11:59 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:11:59 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:11:59 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:11:59 rivo kernel: hdmitx: hw: div40: 0 May 31 22:11:59 rivo kernel: hdmitx: frac_rate = 1 May 31 22:11:59 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:11:59 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:11:59 rivo kernel: hdmitx: config HPLL done May 31 22:11:59 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:11:59 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:11:59 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:11:59 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:11:59 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:11:59 rivo kernel: hdmitx: hw: set audio May 31 22:11:59 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:59 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:59 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:59 rivo kernel: hdmitx: hw: set channel status May 31 22:11:59 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:59 rivo kernel: hdmitx: hw: set audio May 31 22:11:59 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:11:59 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:11:59 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:11:59 rivo kernel: hdmitx: hw: set channel status May 31 22:11:59 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:11:59 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:11:59 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:11:59 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:11:59 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:11:59 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:11:59 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:11:59 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:11:59 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:11:59 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:11:59 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:11:59 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:11:59 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:12:07 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 31 22:12:08 rivo volumio[3188]: info: browseTIDALUri took 1280 milliseconds May 31 22:12:08 rivo volumio[3188]: info: Preload queue cleared May 31 22:12:08 rivo volumio[3188]: info: Preload queue cleared May 31 22:12:08 rivo volumio[3188]: info: Preload queue cleared May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528489 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326648 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528480 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326675 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528497 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528481 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326640 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528494 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326655 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326642 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528487 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528483 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528495 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528484 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528491 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528482 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528486 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528488 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528490 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326644 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326646 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528485 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326680 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805785 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326647 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528496 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/8528493 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326656 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326658 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326618 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805779 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805780 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326653 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326654 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326679 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805775 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805784 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326673 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326672 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326677 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805782 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326657 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326681 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326652 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326617 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326619 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326622 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326678 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/2805781 May 31 22:12:08 rivo volumio[3188]: info: Preloading song: tidal://song/3326614 May 31 22:12:08 rivo volumio[3188]: info: Exploding uri tidal://song/8528489 in service tidal May 31 22:12:08 rivo volumio[3188]: info: Exploding uri tidal://song/3326648 in service tidal May 31 22:12:08 rivo volumio[3188]: info: Exploding uri tidal://song/8528480 in service tidal May 31 22:12:08 rivo volumio[3188]: info: Executing endpoint getSimilarArtists May 31 22:12:08 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 31 22:12:08 rivo volumio[3188]: info: Exploding uri tidal://song/3326675 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528497 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:09 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528481 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326640 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 307 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528494 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326655 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 378 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326642 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528487 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528483 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 299 milliseconds May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 416 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528495 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 511 milliseconds May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 431 milliseconds May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 341 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528484 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528491 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 364 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528482 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 302 milliseconds May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 433 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528486 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528488 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 402 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528490 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 372 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326644 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 385 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326646 in service tidal May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/8528485 in service tidal May 31 22:12:09 rivo volumio[3188]: info: explodeTIDALUri took 419 milliseconds May 31 22:12:09 rivo volumio[3188]: info: Exploding uri tidal://song/3326680 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 519 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805785 in service tidal May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326647 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 501 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/8528496 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 431 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/8528493 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 423 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326656 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 506 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 416 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326658 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 446 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326618 in service tidal May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805779 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 473 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 425 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805780 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 336 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326653 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 365 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 390 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326654 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 392 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 349 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326679 in service tidal May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805775 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 346 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 343 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805784 in service tidal May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326673 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 349 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326672 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 385 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326677 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 357 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/2805782 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 364 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326657 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 356 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326681 in service tidal May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 355 milliseconds May 31 22:12:10 rivo volumio[3188]: info: explodeTIDALUri took 346 milliseconds May 31 22:12:10 rivo volumio[3188]: info: Exploding uri tidal://song/3326652 in service tidal May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 327 milliseconds May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/3326617 in service tidal May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 331 milliseconds May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/3326619 in service tidal May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/3326622 in service tidal May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/3326678 in service tidal May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 391 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 364 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 342 milliseconds May 31 22:12:11 rivo kernel: hdmitx: system: irq 80000004 0 May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/2805781 in service tidal May 31 22:12:11 rivo kernel: hdmitx: system: plugout May 31 22:12:11 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:12:11 rivo kernel: hdmitx: system: irq 80000002 0 May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 342 milliseconds May 31 22:12:11 rivo volumio[3188]: info: Exploding uri tidal://song/3326614 in service tidal May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 313 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 383 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 349 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 346 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 326 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 307 milliseconds May 31 22:12:11 rivo volumio[3188]: info: explodeTIDALUri took 324 milliseconds May 31 22:12:11 rivo kernel: hdmitx: system: plugin May 31 22:12:11 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:12:11 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:11 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:11 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:11 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:11 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:12:11 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:12:11 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:12:11 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:11 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:11 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:12:11 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:11 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:11 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:12:11 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:12:11 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:12:11 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:12:11 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:11 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:12:11 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:12:11 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:11 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:12:11 rivo kernel: hdmitx: hw: div40: 0 May 31 22:12:11 rivo kernel: hdmitx: frac_rate = 1 May 31 22:12:11 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:12:11 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:12:11 rivo kernel: hdmitx: config HPLL done May 31 22:12:11 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:12:11 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:12:11 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:12:12 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:12:12 rivo kernel: hdmitx: system: irq 4 0 May 31 22:12:12 rivo kernel: hdmitx: system: irq 2 0 May 31 22:12:12 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:12:12 rivo kernel: hdmitx: hw: set audio May 31 22:12:12 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:12 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:12 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:12 rivo kernel: hdmitx: hw: set channel status May 31 22:12:12 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:12 rivo kernel: hdmitx: hw: set audio May 31 22:12:12 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:12 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:12 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:12 rivo kernel: hdmitx: hw: set channel status May 31 22:12:12 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:12 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:12:12 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:12:12 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:12:12 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:12:12 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:12:12 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:12:12 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:12:12 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:12:12 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:12:12 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:12:12 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:12:12 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:12:12 rivo kernel: hdmitx: system: plugin May 31 22:12:12 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:12:12 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:12 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:12 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:12 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:12 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:12:12 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:12:12 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:12:12 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:12 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:12 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:12:12 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:12 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:12 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:12:12 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:12:12 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:12:12 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:12:12 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:12 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:12:12 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:12:12 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:12 rivo kernel: hdmitx: system: [set_disp_mode_auto] ALREADY init VIC = 16 May 31 22:12:12 rivo kernel: hdmitx: hw: set audio May 31 22:12:12 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:12 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:12 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:12 rivo kernel: hdmitx: hw: set channel status May 31 22:12:12 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:13 rivo volumio[3188]: info: Preload queue cleared May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::ClearQueue May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::stPlaybackTimer May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::updateTrackBlock May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrackBlock May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::serviceStop May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::serviceStop May 31 22:12:13 rivo volumio[3188]: info: [1780258333294] ControllerTidal::stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 22:12:13 rivo volumio[3188]: info: ControllerMpd::stop May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand stop May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::clearPlayQueue May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::saveQueue May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushQueue May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::addQueueItems May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::addQueueItems May 31 22:12:13 rivo volumio[3188]: info: Preload queue cleared May 31 22:12:13 rivo volumio[3188]: info: Adding Item to queue: tidal://artist/17255082/8528479 May 31 22:12:13 rivo volumio[3188]: info: Exploding uri tidal://artist/17255082/8528479 in service tidal May 31 22:12:13 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable May 31 22:12:13 rivo kernel: spdif_a keep clk continuous May 31 22:12:13 rivo kernel: aml_spdif_close May 31 22:12:13 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 May 31 22:12:13 rivo volumio[3188]: info: May 31 22:12:13 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:13 rivo volumio[3188]: info: sendMpdCommand stop took 42 milliseconds May 31 22:12:13 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:13 rivo volumio[3188]: info: May 31 22:12:13 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:13 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:13 rivo volumio[3188]: info: sendMpdCommand status took 4 milliseconds May 31 22:12:13 rivo volumio[3188]: info: sendMpdCommand status took 2 milliseconds May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:13 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 22:12:13 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 22:12:13 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:13 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:13 rivo volumio[3188]: 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":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:13 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::syncState stateService stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: No code May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:13 rivo volumio[3188]: 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":"65080757&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/65080757&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:13 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::syncState stateService stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus stop May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: No code May 31 22:12:13 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:13 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:13 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:13 rivo volumio[3188]: info: ------------------------------ 140ms May 31 22:12:13 rivo volumio[3188]: info: ------------------------------ 138ms May 31 22:12:13 rivo volumio[3188]: info: Signalling Playback active due to playback status change May 31 22:12:13 rivo volumio[3188]: info: MCU Signalled Playback Inactive May 31 22:12:13 rivo volumio[3188]: info: MCU Signalled Playback Active May 31 22:12:14 rivo volumio[3188]: info: explodeTIDALUri took 891 milliseconds May 31 22:12:14 rivo volumio[3188]: info: CoreCommandRouter::volumioPushQueue May 31 22:12:14 rivo volumio[3188]: info: CorePlayQueue::saveQueue May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::updateTrackBlock May 31 22:12:14 rivo volumio[3188]: info: CorePlayQueue::getTrackBlock May 31 22:12:14 rivo volumio[3188]: info: CoreCommandRouter::volumioPlay May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::play index 0 May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::stop May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::play index undefined May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 22:12:14 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::startPlaybackTimer May 31 22:12:14 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:14 rivo volumio[3188]: info: [1780258334220] ControllerTidal::clearAddPlayTrack May 31 22:12:14 rivo volumio[3188]: info: Getting stream with soundQuality HI_RES May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand stop May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand stop took 6 milliseconds May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand clear May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:12:14 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand clear took 2 milliseconds May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES" May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:12:14 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:12:14 rivo volumio[3188]: error: updateQueue error: null May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:12:14 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:12:14 rivo volumio[3188]: info: ------------------------------ 7ms May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES" took 5 milliseconds May 31 22:12:14 rivo volumio[3188]: info: ------------------------------ 5ms May 31 22:12:14 rivo volumio[3188]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand play May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces system playlist update May 31 22:12:14 rivo volumio[3188]: info: Ignoring MPD Status Update May 31 22:12:14 rivo volumio[3188]: info: ------------------------------ 6ms May 31 22:12:14 rivo volumio[3188]: STREAMING PROXY: Handling url /?data=tidal://song/8528480&soundQuality=HI_RES May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand play took 4 milliseconds May 31 22:12:14 rivo volumio[3188]: info: ------------------------------ 3ms May 31 22:12:14 rivo volumio[3188]: info: Executing endpoint getStreamUrltidal May 31 22:12:14 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 31 22:12:14 rivo volumio[3188]: info: getStreamUrl took 323 milliseconds May 31 22:12:14 rivo volumio[3188]: STREAMING PROXY: Got real url: http://amz-pr-fa.audio.tidal.com/0848258/20140909124214_37.m4a?token=1780261934~MzA1OGJmZDNiZmRhYjY5MGQwYmRmMzVlM2U5YjMyYjJkM2UxZDg0Mg== May 31 22:12:14 rivo volumio[3188]: STREAMING PROXY: Requesting upstream with range: none May 31 22:12:14 rivo volumio[3188]: STREAMING PROXY: Upstream status code: 200 May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:14 rivo kernel: aml_spdif_open May 31 22:12:14 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 May 31 22:12:14 rivo kernel: set normal 512 fs /4 fs May 31 22:12:14 rivo kernel: set spdifout clk:24576000, mpll:98304000 May 31 22:12:14 rivo kernel: get spdifout clk:24575951, mpll:98303801 May 31 22:12:14 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 May 31 22:12:14 rivo kernel: set normal 512 fs /4 fs May 31 22:12:14 rivo kernel: set spdifout clk:24576000, mpll:98304000 May 31 22:12:14 rivo kernel: get spdifout clk:24575951, mpll:98303801 May 31 22:12:14 rivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 May 31 22:12:14 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 May 31 22:12:14 rivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 May 31 22:12:14 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 May 31 22:12:14 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand status took 9 milliseconds May 31 22:12:14 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:14 rivo volumio[3188]: info: May 31 22:12:14 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:14 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:14 rivo volumio[3188]: info: sendMpdCommand status took 14 milliseconds May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:14 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 37 milliseconds May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand status took 36 milliseconds May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 65 milliseconds May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:15 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:15 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:15 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:15 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:15 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus stop May 31 22:12:15 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:15 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:15 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:15 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:12:15 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: ------------------------------ 251ms May 31 22:12:15 rivo volumio[3188]: info: ------------------------------ 281ms May 31 22:12:15 rivo volumio[3188]: info: May 31 22:12:15 rivo volumio[3188]: ---------------------------- MPD announces state update: player May 31 22:12:15 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 203 milliseconds May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand status took 17 milliseconds May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:15 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:15 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:15 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:15 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:12:15 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: ------------------------------ 497ms May 31 22:12:15 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 232 milliseconds May 31 22:12:15 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:15 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:15 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:15 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:15 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:12:15 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:15 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:15 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:15 rivo volumio[3188]: info: ------------------------------ 328ms May 31 22:12:16 rivo volumio[3188]: STREAMING PROXY: Upstream closed. Received 15019209 bytes May 31 22:12:16 rivo volumio[3188]: STREAMING PROXY: Client closed connection May 31 22:12:17 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:17 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:17 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:17 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:17 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:17 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:23 rivo kernel: hdmitx: system: irq 80000004 0 May 31 22:12:23 rivo kernel: hdmitx: system: plugout May 31 22:12:23 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:12:23 rivo kernel: hdmitx: system: irq 80000002 0 May 31 22:12:24 rivo kernel: hdmitx: system: plugin May 31 22:12:24 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:12:24 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:24 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:24 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:24 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:24 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:12:24 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:12:24 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:12:24 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:24 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:24 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:12:24 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:24 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:24 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:12:24 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:12:24 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:12:24 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:12:24 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:24 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:12:24 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:12:24 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:24 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:12:24 rivo kernel: hdmitx: hw: div40: 0 May 31 22:12:24 rivo kernel: hdmitx: frac_rate = 1 May 31 22:12:24 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:12:24 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:12:24 rivo kernel: hdmitx: config HPLL done May 31 22:12:24 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:12:24 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:12:24 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:12:24 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:12:24 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:12:24 rivo kernel: hdmitx: hw: set audio May 31 22:12:24 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:24 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:24 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:24 rivo kernel: hdmitx: hw: set channel status May 31 22:12:24 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:24 rivo kernel: hdmitx: hw: set audio May 31 22:12:24 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:24 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:24 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:24 rivo kernel: hdmitx: hw: set channel status May 31 22:12:24 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:24 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:12:24 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:12:24 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:12:24 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:12:24 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:12:24 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:12:24 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:12:24 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:12:24 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:12:24 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:12:24 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:12:24 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:12:27 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:29 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:29 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:29 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:29 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:29 rivo volumio[3188]: info: Executing endpoint metavolumio May 31 22:12:29 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: Retrieving Cloud Streaming UI May 31 22:12:31 rivo volumio[3188]: info: Getting Tidal Cloud Configuration May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: Getting Qobuz Cloud Configuration May 31 22:12:31 rivo volumio[3188]: info: Asking plugin for UI Config May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: Getting Spotify Cloud Configuration May 31 22:12:31 rivo volumio[3188]: info: Asking plugin for UI Config May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: Saving Spotify Acccount May 31 22:12:31 rivo volumio[3188]: info: Got it May 31 22:12:31 rivo volumio[3188]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 22:12:31 rivo volumio[3188]: info: Got Tidal Cloud Configuration May 31 22:12:31 rivo volumio[3188]: info: Got it May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::volumioGetBrowseSources May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::volumioGetBrowseSources May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::volumioGetBrowseSources May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 22:12:31 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand update May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:35 rivo volumio[3188]: info: May 31 22:12:35 rivo volumio[3188]: ---------------------------- MPD announces state update: update May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand update took 23 milliseconds May 31 22:12:35 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:35 rivo volumio[3188]: info: May 31 22:12:35 rivo volumio[3188]: ---------------------------- MPD announces state update: update May 31 22:12:35 rivo volumio[3188]: info: ControllerMpd::getState May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand status May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand status took 70 milliseconds May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand status took 68 milliseconds May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand status took 68 milliseconds May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand status took 67 milliseconds May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:35 rivo volumio[3188]: info: Command Router : Notfying DB Updatetrue May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::Close All Modals sent May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:35 rivo volumio[3188]: info: Command Router : Notfying DB Updatetrue May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::Close All Modals sent May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseState May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 54 milliseconds May 31 22:12:35 rivo volumio[3188]: info: sendMpdCommand playlistinfo took 16 milliseconds May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:35 rivo volumio[3188]: verbose: ControllerMpd::parseTrackInfo May 31 22:12:35 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:35 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:35 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21520,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:35 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:12:35 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:35 rivo volumio[3188]: info: ControllerMpd::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::servicePushState May 31 22:12:35 rivo volumio[3188]: info: CorePlayQueue::getTrack 0 May 31 22:12:35 rivo volumio[3188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21520,"duration":373,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"8528480&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8528480&soundQuality=HI_RES","trackType":"tidal"} May 31 22:12:35 rivo volumio[3188]: verbose: CURRENT POSITION 0 May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::syncState stateService play May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::syncState currentStatus play May 31 22:12:35 rivo volumio[3188]: info: Received an update from plugin. extracting info from payload May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:35 rivo volumio[3188]: info: CoreStateMachine::pushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioPushState May 31 22:12:35 rivo volumio[3188]: info: CoreCommandRouter::volumioGetState May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output update for this device May 31 22:12:35 rivo volumio[3188]: info: MRS: Pushing multiroomSync output May 31 22:12:35 rivo volumio[3188]: info: ------------------------------ 283ms May 31 22:12:35 rivo volumio[3188]: info: ------------------------------ 282ms May 31 22:12:36 rivo kernel: hdmitx: system: irq 80000004 0 May 31 22:12:36 rivo kernel: hdmitx: system: plugout May 31 22:12:36 rivo kernel: hdmitx: system: update physcial size: 16 9 May 31 22:12:36 rivo kernel: hdmitx: system: irq 80000002 0 May 31 22:12:36 rivo kernel: hdmitx: system: plugin May 31 22:12:37 rivo kernel: hdmitx: edid: EDID Parser: May 31 22:12:37 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:37 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:37 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:37 rivo kernel: hdmitx: hdmitx: reach vesa idx MAX May 31 22:12:37 rivo kernel: hdmitx: edid: change preferred_mode from 0 to 16 May 31 22:12:37 rivo kernel: hdmitx: hdmitx: get PMT vic: 97 May 31 22:12:37 rivo kernel: hdmitx: edid: find IEEEOUT May 31 22:12:37 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:37 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:37 rivo kernel: hdmitx: edid: update rx hdr info f at edid parsing May 31 22:12:37 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:37 rivo kernel: hdmitx: edid: check sum valid May 31 22:12:37 rivo kernel: hdmitx: edid: blk0 raw data May 31 22:12:37 rivo kernel: hdmitx: edid: 00ffffffffffff0034a996a201010101001c0103808048780adaffa3584aa229 17494b2008003140614001010101010101010101010108e80030f2705a80b058 8a00ba882100001e023a801871382d40582c4500ba882100001e000000fc0050 616e61736f6e69632d54560a000000fd00173d0f883c000a202020202020011e May 31 22:12:37 rivo kernel: hdmitx: edid: blk1 raw data May 31 22:12:37 rivo kernel: hdmitx: edid: 02034df057101f05142021220413031207165d5e5f6263646160666523090701 6e030c002000383c2f08800102030467d85dc401788003e2004be305ff01e40f 000078e3060f01e5018b849001662156aa51001e30468f3300ba882100001e00 0000000000000000000000000000000000000000000000000000000000000094 May 31 22:12:37 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:37 rivo kernel: hdmitx: system: get current mode: 1080p60hz May 31 22:12:37 rivo kernel: hdmitx: system: update rx hdr info f May 31 22:12:37 rivo kernel: hdmitx: system: update physcial size: 1280 720 May 31 22:12:37 rivo kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 May 31 22:12:37 rivo kernel: hdmitx: hw: div40: 0 May 31 22:12:37 rivo kernel: hdmitx: frac_rate = 1 May 31 22:12:37 rivo kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 May 31 22:12:37 rivo kernel: hdmitx: HPLLv1: 0xdb3a04f7 May 31 22:12:37 rivo kernel: hdmitx: config HPLL done May 31 22:12:37 rivo kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 May 31 22:12:37 rivo kernel: hdmitx: j = 4 vid_clk_div = 1 May 31 22:12:37 rivo kernel: hdmitx: set enc for VIC: 16 May 31 22:12:37 rivo kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 May 31 22:12:37 rivo kernel: hdmitx: video: Sink is HDMI device May 31 22:12:37 rivo kernel: hdmitx: hw: set audio May 31 22:12:37 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:37 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:37 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:37 rivo kernel: hdmitx: hw: set channel status May 31 22:12:37 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:37 rivo kernel: hdmitx: hw: set audio May 31 22:12:37 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0 May 31 22:12:37 rivo kernel: hdmitx: fs = 7, cd = 4, tmds_clk = 148352 May 31 22:12:37 rivo kernel: hdmitx: hw: aud_n_para = 23296 May 31 22:12:37 rivo kernel: hdmitx: hw: set channel status May 31 22:12:37 rivo kernel: hdmitx: audio: Audio Type: PCM May 31 22:12:37 rivo kernel: hdmitx: system: irq 80000001 0 May 31 22:12:37 rivo kernel: hdmitx: [0x10000103]: 0x00000008 May 31 22:12:37 rivo kernel: hdmitx: [0x10000104]: 0x00000001 May 31 22:12:37 rivo kernel: hdmitx: [0x10000170]: 0x00000010 May 31 22:12:37 rivo kernel: hdmitx: [0x10000180]: 0x000000ff May 31 22:12:37 rivo kernel: hdmitx: [0x10000181]: 0x000000ff May 31 22:12:37 rivo kernel: hdmitx: [0x10000182]: 0x00000003 May 31 22:12:37 rivo kernel: hdmitx: [0x10000183]: 0x00000007 May 31 22:12:37 rivo kernel: hdmitx: [0x10000184]: 0x0000003f May 31 22:12:37 rivo kernel: hdmitx: [0x10000185]: 0x00000002 May 31 22:12:37 rivo kernel: hdmitx: [0x10000187]: 0x000000ff May 31 22:12:37 rivo kernel: hdmitx: [0x10000188]: 0x00000003 May 31 22:12:39 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 22:12:43 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 22:12:45 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 31 22:12:45 rivo volumio[3188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 31 22:12:45 rivo volumio[3188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 22:12:45 rivo volumio[3188]: Error: getaddrinfo -3007 May 31 22:12:45 rivo volumio[3188]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 31 22:12:45 rivo volumio[3188]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 31 22:12:45 rivo volumio[3188]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 31 22:12:45 rivo volumio[3188]: code: -3007, May 31 22:12:45 rivo volumio[3188]: errno: -3007, May 31 22:12:45 rivo volumio[3188]: syscall: 'getaddrinfo' May 31 22:12:45 rivo volumio[3188]: } May 31 22:12:45 rivo volumio[3188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 22:12:46 rivo sudo[7574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 22:11 May 31 22:12:46 rivo sudo[7574]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:38:48 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8e381701610c2a79deb52e712150c089"