Attachment 142116 Details for Bug 108505 – dmesg

dmesg

dmesg (text/plain), 63.62 KB, created by Marcin Deranek on 2018-10-21 08:15:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marcin Deranek

Created: 2018-10-21 08:15:03 UTC

Size: 63.62 KB

patch

obsolete

>[   21.642808] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=745, diff=1, hw=671 hw_last=670
>[   21.655981] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14368, wptr 14384
>[   21.656039] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.656061] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(704,-21)@ 21.902383 -> 21.902724 [e 4 us, 0 rep]
>[   21.656111] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.656124] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=746, diff=1, hw=672 hw_last=671
>[   21.669309] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14384, wptr 14400
>[   21.669368] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.669389] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(735,-21)@ 21.915777 -> 21.916117 [e 4 us, 0 rep]
>[   21.669440] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.669452] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=747, diff=1, hw=673 hw_last=672
>[   21.682641] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14400, wptr 14416
>[   21.682699] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.682721] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(971,-21)@ 21.929173 -> 21.929511 [e 4 us, 0 rep]
>[   21.682771] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   21.682784] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=748, diff=1, hw=674 hw_last=673
>[   21.695966] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14416, wptr 14432
>[   21.696025] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.696046] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(705,-21)@ 21.942563 -> 21.942904 [e 4 us, 0 rep]
>[   21.696096] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.696109] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=749, diff=1, hw=675 hw_last=674
>[   21.709295] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14432, wptr 14448
>[   21.709354] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.709381] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1248,-21)@ 21.955963 -> 21.956297 [e 10 us, 0 rep]
>[   21.709431] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   21.709444] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=750, diff=1, hw=676 hw_last=675
>[   21.722623] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14448, wptr 14464
>[   21.722682] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.722703] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(742,-21)@ 21.969350 -> 21.969691 [e 4 us, 0 rep]
>[   21.722754] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.722766] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=751, diff=1, hw=677 hw_last=676
>[   21.735952] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14464, wptr 14480
>[   21.736011] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.736032] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(777,-21)@ 21.982744 -> 21.983084 [e 4 us, 0 rep]
>[   21.736083] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.736096] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=752, diff=1, hw=678 hw_last=677
>[   21.749285] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14480, wptr 14496
>[   21.749344] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.749366] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1154,-21)@ 21.996142 -> 21.996477 [e 4 us, 0 rep]
>[   21.749416] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   21.749429] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=753, diff=1, hw=679 hw_last=678
>[   21.762609] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14496, wptr 14512
>[   21.762668] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.762689] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(758,-21)@ 22.009530 -> 22.009871 [e 4 us, 0 rep]
>[   21.762739] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.762752] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=754, diff=1, hw=680 hw_last=679
>[   21.775937] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14512, wptr 14528
>[   21.775996] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.776017] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(739,-21)@ 22.022923 -> 22.023264 [e 4 us, 0 rep]
>[   21.776068] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.776080] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=755, diff=1, hw=681 hw_last=680
>[   21.789207] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14528, wptr 14544
>[   21.789266] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   21.789287] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(58,-24)@ 22.036258 -> 22.036657 [e 4 us, 0 rep]
>[   21.789337] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   21.789350] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=756, diff=1, hw=682 hw_last=681
>[   21.802594] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14544, wptr 14560
>[   21.802653] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.802674] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(740,-21)@ 22.049710 -> 22.050051 [e 4 us, 0 rep]
>[   21.802724] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.802737] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=757, diff=1, hw=683 hw_last=682
>[   21.815923] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14560, wptr 14576
>[   21.815981] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.816003] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(747,-21)@ 22.063104 -> 22.063444 [e 4 us, 0 rep]
>[   21.816053] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.816066] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=758, diff=1, hw=684 hw_last=683
>[   21.829195] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14576, wptr 14592
>[   21.829253] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   21.829274] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(183,-24)@ 22.076439 -> 22.076837 [e 4 us, 0 rep]
>[   21.829324] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   21.829337] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=759, diff=1, hw=685 hw_last=684
>[   21.842579] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14592, wptr 14608
>[   21.842638] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.842660] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(728,-21)@ 22.089890 -> 22.090231 [e 4 us, 0 rep]
>[   21.842710] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.842723] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=760, diff=1, hw=686 hw_last=685
>[   21.855908] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14608, wptr 14624
>[   21.855967] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.855988] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(742,-21)@ 22.103283 -> 22.103624 [e 4 us, 0 rep]
>[   21.856038] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.856051] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=761, diff=1, hw=687 hw_last=686
>[   21.869178] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14624, wptr 14640
>[   21.869260] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   21.869281] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(566,-23)@ 22.116641 -> 22.117017 [e 4 us, 0 rep]
>[   21.869331] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -790
>[   21.869344] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=762, diff=1, hw=688 hw_last=687
>[   21.882565] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14640, wptr 14656
>[   21.882623] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.882658] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(484,-20)@ 22.130084 -> 22.130411 [e 18 us, 0 rep]
>[   21.882708] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   21.882721] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=763, diff=1, hw=689 hw_last=688
>[   21.895894] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14656, wptr 14672
>[   21.895952] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.895974] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(760,-21)@ 22.143464 -> 22.143804 [e 4 us, 0 rep]
>[   21.896024] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.896037] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=764, diff=1, hw=690 hw_last=689
>[   21.909222] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14672, wptr 14688
>[   21.909281] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.909302] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(749,-21)@ 22.156857 -> 22.157197 [e 4 us, 0 rep]
>[   21.909352] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.909365] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=765, diff=1, hw=691 hw_last=690
>[   21.922550] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14688, wptr 14704
>[   21.922609] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.922630] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(748,-21)@ 22.170250 -> 22.170590 [e 4 us, 0 rep]
>[   21.922681] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.922693] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=766, diff=1, hw=692 hw_last=691
>[   21.935879] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14704, wptr 14720
>[   21.935938] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.935958] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(746,-21)@ 22.183643 -> 22.183984 [e 4 us, 0 rep]
>[   21.936009] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.936021] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=767, diff=1, hw=693 hw_last=692
>[   21.949207] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14720, wptr 14736
>[   21.949266] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.949288] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(753,-21)@ 22.197037 -> 22.197377 [e 4 us, 0 rep]
>[   21.949338] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.949350] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=768, diff=1, hw=694 hw_last=693
>[   21.962536] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14736, wptr 14752
>[   21.962595] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.962616] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(745,-21)@ 22.210430 -> 22.210770 [e 4 us, 0 rep]
>[   21.962666] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.962679] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=769, diff=1, hw=695 hw_last=694
>[   21.975864] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14752, wptr 14768
>[   21.975923] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.975944] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(745,-21)@ 22.223823 -> 22.224164 [e 4 us, 0 rep]
>[   21.975994] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.976007] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=770, diff=1, hw=696 hw_last=695
>[   21.989193] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14768, wptr 14784
>[   21.989251] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   21.989273] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(747,-21)@ 22.237217 -> 22.237557 [e 4 us, 0 rep]
>[   21.989323] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   21.989336] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=771, diff=1, hw=697 hw_last=696
>[   22.002521] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14784, wptr 14800
>[   22.002579] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.002601] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(680,-21)@ 22.250609 -> 22.250950 [e 4 us, 0 rep]
>[   22.002651] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.002664] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=772, diff=1, hw=698 hw_last=697
>[   22.015836] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14800, wptr 14816
>[   22.015895] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.015916] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(975,-22)@ 22.263989 -> 22.264344 [e 4 us, 0 rep]
>[   22.015969] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.015985] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=773, diff=1, hw=699 hw_last=698
>[   22.029138] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14816, wptr 14832
>[   22.029200] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -794
>[   22.029221] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(444,-23)@ 22.277359 -> 22.277737 [e 4 us, 0 rep]
>[   22.029271] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -790
>[   22.029284] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=774, diff=1, hw=700 hw_last=699
>[   22.042470] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14832, wptr 14848
>[   22.042555] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.042579] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1252,-22)@ 22.290780 -> 22.291130 [e 4 us, 0 rep]
>[   22.042635] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.042651] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=775, diff=1, hw=701 hw_last=700
>[   22.055753] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14848, wptr 14864
>[   22.055780] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -797
>[   22.055800] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(860,-28)@ 22.304068 -> 22.304524 [e 5 us, 0 rep]
>[   22.055821] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.055826] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=776, diff=1, hw=702 hw_last=701
>[   22.069060] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14864, wptr 14880
>[   22.069085] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.069095] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(348,-29)@ 22.317437 -> 22.317916 [e 4 us, 0 rep]
>[   22.069116] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -797
>[   22.069121] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=777, diff=1, hw=703 hw_last=702
>[   22.082394] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14880, wptr 14896
>[   22.082422] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.082434] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1056,-29)@ 22.330840 -> 22.331310 [e 4 us, 0 rep]
>[   22.082457] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   22.082463] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=778, diff=1, hw=704 hw_last=703
>[   22.095721] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14896, wptr 14912
>[   22.095746] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.095757] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(691,-29)@ 22.344229 -> 22.344703 [e 4 us, 0 rep]
>[   22.095778] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   22.095783] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=779, diff=1, hw=705 hw_last=704
>[   22.109047] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14912, wptr 14928
>[   22.109073] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.109084] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(570,-29)@ 22.357620 -> 22.358096 [e 4 us, 0 rep]
>[   22.109107] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   22.109112] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=780, diff=1, hw=706 hw_last=705
>[   22.122476] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14928, wptr 14944
>[   22.122534] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.122556] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(592,-21)@ 22.371148 -> 22.371490 [e 4 us, 0 rep]
>[   22.122606] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.122619] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=781, diff=1, hw=707 hw_last=706
>[   22.135705] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14944, wptr 14960
>[   22.135730] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.135740] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(547,-29)@ 22.384407 -> 22.384883 [e 4 us, 0 rep]
>[   22.135761] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   22.135766] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=782, diff=1, hw=708 hw_last=707
>[   22.149135] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14960, wptr 14976
>[   22.149194] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.149215] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(832,-21)@ 22.397938 -> 22.398277 [e 4 us, 0 rep]
>[   22.149265] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.149278] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=783, diff=1, hw=709 hw_last=708
>[   22.162463] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14976, wptr 14992
>[   22.162522] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.162543] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(752,-21)@ 22.411330 -> 22.411670 [e 4 us, 0 rep]
>[   22.162593] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.162606] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=784, diff=1, hw=710 hw_last=709
>[   22.175740] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 14992, wptr 15008
>[   22.175765] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.175775] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(530,-26)@ 22.424637 -> 22.425063 [e 4 us, 0 rep]
>[   22.175796] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.175800] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=785, diff=1, hw=711 hw_last=710
>[   22.189119] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15008, wptr 15024
>[   22.189178] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.189199] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(692,-21)@ 22.438116 -> 22.438457 [e 4 us, 0 rep]
>[   22.189250] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.189262] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=786, diff=1, hw=712 hw_last=711
>[   22.202448] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15024, wptr 15040
>[   22.202507] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.202528] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(750,-21)@ 22.451510 -> 22.451850 [e 4 us, 0 rep]
>[   22.202578] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.202591] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=787, diff=1, hw=713 hw_last=712
>[   22.215763] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15040, wptr 15056
>[   22.215824] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.215845] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1139,-22)@ 22.464891 -> 22.465244 [e 4 us, 0 rep]
>[   22.215895] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.215908] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=788, diff=1, hw=714 hw_last=713
>[   22.229106] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15056, wptr 15072
>[   22.229164] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.229185] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(773,-21)@ 22.478297 -> 22.478637 [e 4 us, 0 rep]
>[   22.229243] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.229255] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=789, diff=1, hw=715 hw_last=714
>[   22.242433] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15072, wptr 15088
>[   22.242492] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.242513] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(684,-21)@ 22.491689 -> 22.492030 [e 4 us, 0 rep]
>[   22.242563] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.242576] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=790, diff=1, hw=716 hw_last=715
>[   22.255762] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15088, wptr 15104
>[   22.255821] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.255842] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(748,-21)@ 22.505080 -> 22.505421 [e 4 us, 0 rep]
>[   22.255892] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.255905] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=791, diff=1, hw=717 hw_last=716
>[   22.269090] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15104, wptr 15120
>[   22.269149] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.269170] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(701,-21)@ 22.518465 -> 22.518806 [e 4 us, 0 rep]
>[   22.269220] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.269233] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=792, diff=1, hw=718 hw_last=717
>[   22.282418] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15120, wptr 15136
>[   22.282477] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.282499] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(712,-21)@ 22.531850 -> 22.532191 [e 4 us, 0 rep]
>[   22.282549] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.282562] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=793, diff=1, hw=719 hw_last=718
>[   22.295748] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15136, wptr 15152
>[   22.295806] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.295827] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(731,-21)@ 22.545236 -> 22.545576 [e 4 us, 0 rep]
>[   22.295878] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.295890] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=794, diff=1, hw=720 hw_last=719
>[   22.309076] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15152, wptr 15168
>[   22.309135] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.309156] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(731,-21)@ 22.558621 -> 22.558961 [e 4 us, 0 rep]
>[   22.309206] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.309219] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=795, diff=1, hw=721 hw_last=720
>[   22.322405] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15168, wptr 15184
>[   22.322463] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.322490] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(751,-21)@ 22.572006 -> 22.572347 [e 4 us, 0 rep]
>[   22.322540] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.322552] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=796, diff=1, hw=722 hw_last=721
>[   22.335732] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15184, wptr 15200
>[   22.335791] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.335813] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(700,-21)@ 22.585391 -> 22.585732 [e 4 us, 0 rep]
>[   22.335863] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.335876] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=797, diff=1, hw=723 hw_last=722
>[   22.349062] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15200, wptr 15216
>[   22.349120] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.349141] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(767,-21)@ 22.598777 -> 22.599117 [e 4 us, 0 rep]
>[   22.349192] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.349204] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=798, diff=1, hw=724 hw_last=723
>[   22.362401] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15216, wptr 15232
>[   22.362460] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   22.362482] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(346,-20)@ 22.612173 -> 22.612502 [e 4 us, 0 rep]
>[   22.362532] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.362544] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=799, diff=1, hw=725 hw_last=724
>[   22.375718] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15232, wptr 15248
>[   22.375776] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.375798] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(685,-21)@ 22.625546 -> 22.625887 [e 4 us, 0 rep]
>[   22.375848] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.375861] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=800, diff=1, hw=726 hw_last=725
>[   22.389047] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15248, wptr 15264
>[   22.389105] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.389127] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(729,-21)@ 22.638932 -> 22.639273 [e 4 us, 0 rep]
>[   22.389177] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.389190] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=801, diff=1, hw=727 hw_last=726
>[   22.402361] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15264, wptr 15280
>[   22.402419] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.402447] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(910,-22)@ 22.652303 -> 22.652658 [e 4 us, 0 rep]
>[   22.402498] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -786
>[   22.402503] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=802, diff=1, hw=728 hw_last=727
>[   22.415704] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15280, wptr 15296
>[   22.415762] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.415784] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(724,-21)@ 22.665702 -> 22.666043 [e 4 us, 0 rep]
>[   22.415834] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.415847] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=803, diff=1, hw=729 hw_last=728
>[   22.429033] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15296, wptr 15312
>[   22.429091] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.429112] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(718,-21)@ 22.679088 -> 22.679428 [e 4 us, 0 rep]
>[   22.429162] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.429175] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=804, diff=1, hw=730 hw_last=729
>[   22.442280] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15312, wptr 15328
>[   22.442305] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -797
>[   22.442315] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(860,-28)@ 22.692357 -> 22.692813 [e 4 us, 0 rep]
>[   22.442337] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.442341] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=805, diff=1, hw=731 hw_last=730
>[   22.455638] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15328, wptr 15344
>[   22.455663] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.455673] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(523,-26)@ 22.705772 -> 22.706198 [e 4 us, 0 rep]
>[   22.455697] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.455701] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=806, diff=1, hw=732 hw_last=731
>[   22.469018] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15344, wptr 15360
>[   22.469076] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.469097] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(713,-21)@ 22.719243 -> 22.719584 [e 4 us, 0 rep]
>[   22.469147] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.469160] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=807, diff=1, hw=733 hw_last=732
>[   22.482244] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15360, wptr 15376
>[   22.482269] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -798
>[   22.482279] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(491,-29)@ 22.732492 -> 22.732968 [e 4 us, 0 rep]
>[   22.482301] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   22.482305] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=808, diff=1, hw=734 hw_last=733
>[   22.495675] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15376, wptr 15392
>[   22.495733] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.495755] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(748,-21)@ 22.746014 -> 22.746354 [e 4 us, 0 rep]
>[   22.495805] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.495818] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=809, diff=1, hw=735 hw_last=734
>[   22.509003] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15392, wptr 15408
>[   22.509062] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.509083] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(748,-21)@ 22.759399 -> 22.759740 [e 4 us, 0 rep]
>[   22.509134] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.509146] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=810, diff=1, hw=736 hw_last=735
>[   22.522289] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15408, wptr 15424
>[   22.522315] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.522325] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1255,-26)@ 22.772707 -> 22.773124 [e 4 us, 0 rep]
>[   22.522346] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.522350] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=811, diff=1, hw=737 hw_last=736
>[   22.535660] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15424, wptr 15440
>[   22.535718] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.535740] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(729,-21)@ 22.786169 -> 22.786510 [e 4 us, 0 rep]
>[   22.535790] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.535803] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=812, diff=1, hw=738 hw_last=737
>[   22.548988] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15440, wptr 15456
>[   22.549047] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.549069] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(752,-21)@ 22.799555 -> 22.799895 [e 4 us, 0 rep]
>[   22.549119] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.549132] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=813, diff=1, hw=739 hw_last=738
>[   22.562304] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15456, wptr 15472
>[   22.562378] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.562399] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(935,-21)@ 22.812942 -> 22.813280 [e 4 us, 0 rep]
>[   22.562449] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.562462] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=814, diff=1, hw=740 hw_last=739
>[   22.575646] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15472, wptr 15488
>[   22.575704] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.575726] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(762,-21)@ 22.826326 -> 22.826666 [e 4 us, 0 rep]
>[   22.575796] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -786
>[   22.575811] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=815, diff=1, hw=741 hw_last=740
>[   22.588973] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15488, wptr 15504
>[   22.589032] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.589054] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(701,-21)@ 22.839710 -> 22.840051 [e 4 us, 0 rep]
>[   22.589104] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.589116] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=816, diff=1, hw=742 hw_last=741
>[   22.602303] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15504, wptr 15520
>[   22.602361] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.602383] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(759,-21)@ 22.853096 -> 22.853436 [e 4 us, 0 rep]
>[   22.602433] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.602445] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=817, diff=1, hw=743 hw_last=742
>[   22.615630] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15520, wptr 15536
>[   22.615689] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.615711] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(717,-21)@ 22.866481 -> 22.866821 [e 4 us, 0 rep]
>[   22.615761] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.615774] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=818, diff=1, hw=744 hw_last=743
>[   22.628959] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15536, wptr 15552
>[   22.629017] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.629039] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(702,-21)@ 22.879866 -> 22.880207 [e 4 us, 0 rep]
>[   22.629089] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.629102] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=819, diff=1, hw=745 hw_last=744
>[   22.642288] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15552, wptr 15568
>[   22.642347] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.642368] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(762,-21)@ 22.893252 -> 22.893592 [e 4 us, 0 rep]
>[   22.642418] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.642431] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=820, diff=1, hw=746 hw_last=745
>[   22.655616] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15568, wptr 15584
>[   22.655674] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.655696] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(702,-21)@ 22.906636 -> 22.906977 [e 4 us, 0 rep]
>[   22.655746] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.655759] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=821, diff=1, hw=747 hw_last=746
>[   22.668945] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15584, wptr 15600
>[   22.669003] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.669025] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(744,-21)@ 22.920022 -> 22.920362 [e 4 us, 0 rep]
>[   22.669075] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.669087] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=822, diff=1, hw=748 hw_last=747
>[   22.682274] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15600, wptr 15616
>[   22.682332] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.682354] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(773,-21)@ 22.933407 -> 22.933747 [e 4 us, 0 rep]
>[   22.682404] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.682416] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=823, diff=1, hw=749 hw_last=748
>[   22.695602] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15616, wptr 15632
>[   22.695660] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.695682] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(732,-21)@ 22.946792 -> 22.947133 [e 4 us, 0 rep]
>[   22.695731] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.695744] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=824, diff=1, hw=750 hw_last=749
>[   22.708939] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15632, wptr 15648
>[   22.708998] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   22.709020] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(155,-20)@ 22.960187 -> 22.960518 [e 4 us, 0 rep]
>[   22.709070] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.709082] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=825, diff=1, hw=751 hw_last=750
>[   22.722258] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15648, wptr 15664
>[   22.722316] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.722338] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(679,-21)@ 22.973562 -> 22.973903 [e 4 us, 0 rep]
>[   22.722388] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.722401] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=826, diff=1, hw=752 hw_last=751
>[   22.735587] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15664, wptr 15680
>[   22.735655] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   22.735676] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(176,-20)@ 22.986957 -> 22.987288 [e 4 us, 0 rep]
>[   22.735726] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.735739] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=827, diff=1, hw=753 hw_last=752
>[   22.748901] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15680, wptr 15696
>[   22.748966] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.748994] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(66,-21)@ 23.000324 -> 23.000673 [e 4 us, 0 rep]
>[   22.749015] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.749019] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=828, diff=1, hw=754 hw_last=753
>[   22.762244] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15696, wptr 15712
>[   22.762303] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.762325] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(792,-21)@ 23.013719 -> 23.014059 [e 4 us, 0 rep]
>[   22.762375] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.762388] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=829, diff=1, hw=755 hw_last=754
>[   22.775572] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15712, wptr 15728
>[   22.775630] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.775652] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(676,-21)@ 23.027103 -> 23.027444 [e 4 us, 0 rep]
>[   22.775702] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.775715] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=830, diff=1, hw=756 hw_last=755
>[   22.788851] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15728, wptr 15744
>[   22.788876] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.788886] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(658,-26)@ 23.040404 -> 23.040829 [e 4 us, 0 rep]
>[   22.788908] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.788912] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=831, diff=1, hw=757 hw_last=756
>[   22.802230] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15744, wptr 15760
>[   22.802289] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.802310] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(786,-21)@ 23.053874 -> 23.054214 [e 4 us, 0 rep]
>[   22.802360] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.802373] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=832, diff=1, hw=758 hw_last=757
>[   22.815557] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15760, wptr 15776
>[   22.815616] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.815638] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(692,-21)@ 23.067258 -> 23.067600 [e 4 us, 0 rep]
>[   22.815688] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.815701] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=833, diff=1, hw=759 hw_last=758
>[   22.828836] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15776, wptr 15792
>[   22.828861] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -795
>[   22.828871] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(575,-26)@ 23.080558 -> 23.080984 [e 4 us, 0 rep]
>[   22.828892] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.828896] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=834, diff=1, hw=760 hw_last=759
>[   22.842215] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15792, wptr 15808
>[   22.842274] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.842295] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(767,-21)@ 23.094030 -> 23.094370 [e 4 us, 0 rep]
>[   22.842345] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.842358] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=835, diff=1, hw=761 hw_last=760
>[   22.855542] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15808, wptr 15824
>[   22.855600] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.855622] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(620,-21)@ 23.107413 -> 23.107755 [e 4 us, 0 rep]
>[   22.855672] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.855685] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=836, diff=1, hw=762 hw_last=761
>[   22.868871] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15824, wptr 15840
>[   22.868930] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.868951] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(696,-21)@ 23.120799 -> 23.121140 [e 4 us, 0 rep]
>[   22.869001] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.869014] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=837, diff=1, hw=763 hw_last=762
>[   22.882200] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15840, wptr 15856
>[   22.882259] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.882280] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(732,-21)@ 23.134185 -> 23.134526 [e 4 us, 0 rep]
>[   22.882330] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.882343] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=838, diff=1, hw=764 hw_last=763
>[   22.895528] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15856, wptr 15872
>[   22.895587] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.895608] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(687,-21)@ 23.147570 -> 23.147911 [e 4 us, 0 rep]
>[   22.895658] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.895671] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=839, diff=1, hw=765 hw_last=764
>[   22.908857] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15872, wptr 15888
>[   22.908932] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   22.908954] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(720,-20)@ 23.160972 -> 23.161296 [e 4 us, 0 rep]
>[   22.909004] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   22.909022] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=840, diff=1, hw=766 hw_last=765
>[   22.922171] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15888, wptr 15904
>[   22.922232] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   22.922254] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1116,-22)@ 23.174329 -> 23.174681 [e 4 us, 0 rep]
>[   22.922304] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.922316] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=841, diff=1, hw=767 hw_last=766
>[   22.935514] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15904, wptr 15920
>[   22.935572] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.935593] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(675,-21)@ 23.187725 -> 23.188067 [e 4 us, 0 rep]
>[   22.935643] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.935656] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=842, diff=1, hw=768 hw_last=767
>[   22.948843] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15920, wptr 15936
>[   22.948902] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.948923] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(770,-21)@ 23.201112 -> 23.201452 [e 4 us, 0 rep]
>[   22.948973] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.948986] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=843, diff=1, hw=769 hw_last=768
>[   22.962171] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15936, wptr 15952
>[   22.962230] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.962251] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(729,-21)@ 23.214496 -> 23.214837 [e 4 us, 0 rep]
>[   22.962301] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.962314] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=844, diff=1, hw=770 hw_last=769
>[   22.975500] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15952, wptr 15968
>[   22.975558] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.975580] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(747,-21)@ 23.227882 -> 23.228222 [e 4 us, 0 rep]
>[   22.975630] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.975642] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=845, diff=1, hw=771 hw_last=770
>[   22.988828] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15968, wptr 15984
>[   22.988886] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   22.988908] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(723,-21)@ 23.241267 -> 23.241607 [e 4 us, 0 rep]
>[   22.988958] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   22.988974] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=846, diff=1, hw=772 hw_last=771
>[   23.002156] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 15984, wptr 16000
>[   23.002215] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.002237] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(752,-21)@ 23.254652 -> 23.254993 [e 4 us, 0 rep]
>[   23.002287] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   23.002300] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=847, diff=1, hw=773 hw_last=772
>[   23.015425] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16000, wptr 16016
>[   23.015484] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   23.015505] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1272,-25)@ 23.267977 -> 23.268378 [e 4 us, 0 rep]
>[   23.015558] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   23.015571] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=848, diff=1, hw=774 hw_last=773
>[   23.028817] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16016, wptr 16032
>[   23.028875] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.028897] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(993,-21)@ 23.281426 -> 23.281763 [e 4 us, 0 rep]
>[   23.028947] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   23.028959] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=849, diff=1, hw=775 hw_last=774
>[   23.042142] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16032, wptr 16048
>[   23.042200] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.042222] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(739,-21)@ 23.294808 -> 23.295148 [e 4 us, 0 rep]
>[   23.042272] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   23.042285] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=850, diff=1, hw=776 hw_last=775
>[   23.055475] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16048, wptr 16064
>[   23.055534] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.055556] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1152,-21)@ 23.308198 -> 23.308533 [e 4 us, 0 rep]
>[   23.055606] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   23.055619] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=851, diff=1, hw=777 hw_last=776
>[   23.068802] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16064, wptr 16080
>[   23.068861] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.068883] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1012,-21)@ 23.321582 -> 23.321919 [e 4 us, 0 rep]
>[   23.068933] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -787
>[   23.068945] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=852, diff=1, hw=778 hw_last=777
>[   23.082127] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16080, wptr 16096
>[   23.082209] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -791
>[   23.082231] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1284,-20)@ 23.334987 -> 23.335304 [e 4 us, 0 rep]
>[   23.082281] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -786
>[   23.082294] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=853, diff=1, hw=779 hw_last=778
>[   23.095444] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16096, wptr 16112
>[   23.095503] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.095524] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1148,-22)@ 23.348337 -> 23.348689 [e 4 us, 0 rep]
>[   23.095575] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   23.095587] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=854, diff=1, hw=780 hw_last=779
>[   23.108784] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16112, wptr 16128
>[   23.108842] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.108864] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(705,-21)@ 23.361733 -> 23.362074 [e 4 us, 0 rep]
>[   23.108914] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   23.108927] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=855, diff=1, hw=781 hw_last=780
>[   23.122112] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16128, wptr 16144
>[   23.122171] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.122193] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(733,-21)@ 23.375119 -> 23.375460 [e 4 us, 0 rep]
>[   23.122243] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -788
>[   23.122256] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=856, diff=1, hw=782 hw_last=781
>[   23.135358] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16144, wptr 16160
>[   23.135396] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -797
>[   23.135410] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(341,-27)@ 23.388398 -> 23.388844 [e 2 us, 0 rep]
>[   23.135443] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -794
>[   23.135452] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=857, diff=1, hw=783 hw_last=782
>[   23.148753] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16160, wptr 16176
>[   23.148808] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.148828] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(398,-22)@ 23.401868 -> 23.402229 [e 3 us, 0 rep]
>[   23.148876] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.148888] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=858, diff=1, hw=784 hw_last=783
>[   23.162082] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16176, wptr 16192
>[   23.162137] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.162157] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(455,-22)@ 23.415254 -> 23.415614 [e 3 us, 0 rep]
>[   23.162205] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.162217] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=859, diff=1, hw=785 hw_last=784
>[   23.175365] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16192, wptr 16208
>[   23.175419] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -796
>[   23.175439] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(724,-25)@ 23.428592 -> 23.428999 [e 3 us, 0 rep]
>[   23.175487] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -792
>[   23.175500] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=860, diff=1, hw=786 hw_last=785
>[   23.188739] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16208, wptr 16224
>[   23.188794] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.188814] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(433,-22)@ 23.442024 -> 23.442385 [e 3 us, 0 rep]
>[   23.188862] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.188874] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=861, diff=1, hw=787 hw_last=786
>[   23.202066] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16224, wptr 16240
>[   23.202122] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.202142] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(372,-22)@ 23.455408 -> 23.455770 [e 3 us, 0 rep]
>[   23.202189] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.202202] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=862, diff=1, hw=788 hw_last=787
>[   23.215395] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16240, wptr 16256
>[   23.215450] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.215471] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(403,-22)@ 23.468794 -> 23.469155 [e 3 us, 0 rep]
>[   23.215518] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.215531] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=863, diff=1, hw=789 hw_last=788
>[   23.228724] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16256, wptr 16272
>[   23.228780] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.228800] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(450,-22)@ 23.482180 -> 23.482540 [e 3 us, 0 rep]
>[   23.228847] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.228860] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=864, diff=1, hw=790 hw_last=789
>[   23.242052] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16272, wptr 16288
>[   23.242107] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.242127] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(384,-22)@ 23.495564 -> 23.495926 [e 3 us, 0 rep]
>[   23.242174] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.242187] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=865, diff=1, hw=791 hw_last=790
>[   23.255382] [drm:amdgpu_ih_process [amdgpu]] amdgpu_ih_process: rptr 16288, wptr 16304
>[   23.255437] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -793
>[   23.255457] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(483,-22)@ 23.508946 -> 23.509306 [e 3 us, 0 rep]
>[   23.255504] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -789
>[   23.255517] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=866, diff=1, hw=792 hw_last=791
>[   23.266650] [drm:vblank_disable_fn [drm]] disabling vblank on crtc 0
>[   23.266677] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -117
>[   23.266686] [drm:drm_calc_vbltimestamp_from_scanoutpos [drm]] crtc 0 : v p(1226,652)@ 23.520226 -> 23.509348 [e 2 us, 0 rep]
>[   23.266707] [drm:amdgpu_get_vblank_counter_kms [amdgpu]] crtc 0: dist from vblank start -115
>[   23.266712] [drm:drm_update_vblank_count [drm]] updating vblank count on crtc 0: current=867, diff=0, hw=792 hw_last=792

Actions: View

Attachments on bug 108505: 142115 | 142116 | 142120

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.