Results for igt@kms_flip@plain-flip-ts-check@b-hdmi-a2

Machine description: shard-rkl-1

Result: Fail

git-log-oneline i915_display_info14 igt_runner14 runtimes14 results14.json results14-i915-load.json i915_display_info_post_exec14 boot14 dmesg14

DetailValue
Duration 18.64 seconds
Hostname 
shard-rkl-1
Igt-Version 
IGT-Version: 2.0-g87be12f9e (x86_64) (Linux: 6.14.0-rc7-CI_DRM_16301-ga123616cf3af+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19066.1us +- 8.131us accuracy 0.13%
vblank interval differs from modeline! expected 18973.8us, measured 19066us +- 8.131us, difference 92.4us (11.4 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1831 run_test()
  #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
  #4 ../tests/kms_flip.c:1987 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A2: FAIL (18.640s)
Subtest plain-flip-ts-check: FAIL (25.320s)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2246 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2935 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2390 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: B-HDMI-A2
(kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.333565
last_received_ts = 81.332870
last_seq = 688
current_ts = 81.352554
current_received_ts = 81.351860
current_seq = 689
count = 668
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.333565/688, current 81.352554/689: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.352554
last_received_ts = 81.351860
last_seq = 689
current_ts = 81.371544
current_received_ts = 81.370857
current_seq = 690
count = 669
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.352554/689, current 81.371544/690: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.371544
last_received_ts = 81.370857
last_seq = 690
current_ts = 81.390533
current_received_ts = 81.389847
current_seq = 691
count = 670
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.371544/690, current 81.390533/691: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.390533
last_received_ts = 81.389847
last_seq = 691
current_ts = 81.409523
current_received_ts = 81.408836
current_seq = 692
count = 671
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.390533/691, current 81.409523/692: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.409523
last_received_ts = 81.408836
last_seq = 692
current_ts = 81.428513
current_received_ts = 81.427826
current_seq = 693
count = 672
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.409523/692, current 81.428513/693: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.428513
last_received_ts = 81.427826
last_seq = 693
current_ts = 81.447502
current_received_ts = 81.446815
current_seq = 694
count = 673
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.428513/693, current 81.447502/694: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.447502
last_received_ts = 81.446815
last_seq = 694
current_ts = 81.466492
current_received_ts = 81.465805
current_seq = 695
count = 674
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.447502/694, current 81.466492/695: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.466492
last_received_ts = 81.465805
last_seq = 695
current_ts = 81.485481
current_received_ts = 81.484795
current_seq = 696
count = 675
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.466492/695, current 81.485481/696: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.485481
last_received_ts = 81.484795
last_seq = 696
current_ts = 81.504478
current_received_ts = 81.503784
current_seq = 697
count = 676
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.485481/696, current 81.504478/697: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.504478
last_received_ts = 81.503784
last_seq = 697
current_ts = 81.523468
current_received_ts = 81.522774
current_seq = 698
count = 677
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.504478/697, current 81.523468/698: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.523468
last_received_ts = 81.522774
last_seq = 698
current_ts = 81.542458
current_received_ts = 81.541763
current_seq = 699
count = 678
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.523468/698, current 81.542458/699: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.542458
last_received_ts = 81.541763
last_seq = 699
current_ts = 81.561447
current_received_ts = 81.560753
current_seq = 700
count = 679
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.542458/699, current 81.561447/700: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.561447
last_received_ts = 81.560753
last_seq = 700
current_ts = 81.580437
current_received_ts = 81.579742
current_seq = 701
count = 680
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.561447/700, current 81.580437/701: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.580437
last_received_ts = 81.579742
last_seq = 701
current_ts = 81.599426
current_received_ts = 81.598732
current_seq = 702
count = 681
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.580437/701, current 81.599426/702: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.599426
last_received_ts = 81.598732
last_seq = 702
current_ts = 81.618416
current_received_ts = 81.617729
current_seq = 703
count = 682
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.599426/702, current 81.618416/703: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.618416
last_received_ts = 81.617729
last_seq = 703
current_ts = 81.637405
current_received_ts = 81.636719
current_seq = 704
count = 683
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.618416/703, current 81.637405/704: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.637405
last_received_ts = 81.636719
last_seq = 704
current_ts = 81.656395
current_received_ts = 81.655708
current_seq = 705
count = 684
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.637405/704, current 81.656395/705: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.656395
last_received_ts = 81.655708
last_seq = 705
current_ts = 81.675385
current_received_ts = 81.674698
current_seq = 706
count = 685
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.656395/705, current 81.675385/706: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.675385
last_received_ts = 81.674698
last_seq = 706
current_ts = 81.694374
current_received_ts = 81.693687
current_seq = 707
count = 686
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.675385/706, current 81.694374/707: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.694374
last_received_ts = 81.693687
last_seq = 707
current_ts = 81.713364
current_received_ts = 81.712677
current_seq = 708
count = 687
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.694374/707, current 81.713364/708: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.713364
last_received_ts = 81.712677
last_seq = 708
current_ts = 81.732361
current_received_ts = 81.731667
current_seq = 709
count = 688
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.713364/708, current 81.732361/709: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.732361
last_received_ts = 81.731667
last_seq = 709
current_ts = 81.751350
current_received_ts = 81.750656
current_seq = 710
count = 689
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.732361/709, current 81.751350/710: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.751350
last_received_ts = 81.750656
last_seq = 710
current_ts = 81.770340
current_received_ts = 81.769646
current_seq = 711
count = 690
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.751350/710, current 81.770340/711: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.770340
last_received_ts = 81.769646
last_seq = 711
current_ts = 81.789330
current_received_ts = 81.788635
current_seq = 712
count = 691
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.770340/711, current 81.789330/712: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.789330
last_received_ts = 81.788635
last_seq = 712
current_ts = 81.808319
current_received_ts = 81.807625
current_seq = 713
count = 692
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.789330/712, current 81.808319/713: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.808319
last_received_ts = 81.807625
last_seq = 713
current_ts = 81.827309
current_received_ts = 81.826630
current_seq = 714
count = 693
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.808319/713, current 81.827309/714: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.827309
last_received_ts = 81.826630
last_seq = 714
current_ts = 81.846298
current_received_ts = 81.845612
current_seq = 715
count = 694
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.827309/714, current 81.846298/715: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.846298
last_received_ts = 81.845612
last_seq = 715
current_ts = 81.865288
current_received_ts = 81.864601
current_seq = 716
count = 695
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.846298/715, current 81.865288/716: elapsed=18991.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.865288
last_received_ts = 81.864601
last_seq = 716
current_ts = 81.884277
current_received_ts = 81.883591
current_seq = 717
count = 696
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.865288/716, current 81.884277/717: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.884277
last_received_ts = 81.883591
last_seq = 717
current_ts = 81.903267
current_received_ts = 81.902580
current_seq = 718
count = 697
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.884277/717, current 81.903267/718: elapsed=18990.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.903267
last_received_ts = 81.902580
last_seq = 718
current_ts = 81.922256
current_received_ts = 81.921570
current_seq = 719
count = 698
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.903267/718, current 81.922256/719: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.922256
last_received_ts = 81.921570
last_seq = 719
current_ts = 81.941246
current_received_ts = 81.940559
current_seq = 720
count = 699
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.922256/719, current 81.941246/720: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.941246
last_received_ts = 81.940559
last_seq = 720
current_ts = 81.960236
current_received_ts = 81.959541
current_seq = 721
count = 700
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.941246/720, current 81.960236/721: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.960236
last_received_ts = 81.959541
last_seq = 721
current_ts = 81.979225
current_received_ts = 81.978531
current_seq = 722
count = 701
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.960236/721, current 81.979225/722: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.979225
last_received_ts = 81.978531
last_seq = 722
current_ts = 81.998207
current_received_ts = 81.997520
current_seq = 723
count = 702
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.979225/722, current 81.998207/723: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.998207
last_received_ts = 81.997520
last_seq = 723
current_ts = 82.017197
current_received_ts = 82.016510
current_seq = 724
count = 703
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.998207/723, current 82.017197/724: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.017197
last_received_ts = 82.016510
last_seq = 724
current_ts = 82.036186
current_received_ts = 82.035492
current_seq = 725
count = 704
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.017197/724, current 82.036186/725: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.036186
last_received_ts = 82.035492
last_seq = 725
current_ts = 82.055191
current_received_ts = 82.054466
current_seq = 726
count = 705
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.036186/725, current 82.055191/726: elapsed=19002.0us expected=19066.1us +- 95.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.055191
last_received_ts = 82.054466
last_seq = 726
current_ts = 82.074165
current_received_ts = 82.073471
current_seq = 727
count = 706
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.055191/726, current 82.074165/727: elapsed=18975.0us expected=19066.1us +- 95.3us, error 0.5%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.074165
last_received_ts = 82.073471
last_seq = 727
current_ts = 82.093147
current_received_ts = 82.092461
current_seq = 728
count = 707
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.074165/727, current 82.093147/728: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.093147
last_received_ts = 82.092461
last_seq = 728
current_ts = 82.112137
current_received_ts = 82.111450
current_seq = 729
count = 708
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.093147/728, current 82.112137/729: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.112137
last_received_ts = 82.111450
last_seq = 729
current_ts = 82.131126
current_received_ts = 82.130440
current_seq = 730
count = 709
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.112137/729, current 82.131126/730: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.131126
last_received_ts = 82.130440
last_seq = 730
current_ts = 82.150116
current_received_ts = 82.149429
current_seq = 731
count = 710
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.131126/730, current 82.150116/731: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.150116
last_received_ts = 82.149429
last_seq = 731
current_ts = 82.169106
current_received_ts = 82.168411
current_seq = 732
count = 711
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.150116/731, current 82.169106/732: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.169106
last_received_ts = 82.168411
last_seq = 732
current_ts = 82.188087
current_received_ts = 82.187401
current_seq = 733
count = 712
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.169106/732, current 82.188087/733: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.188087
last_received_ts = 82.187401
last_seq = 733
current_ts = 82.207077
current_received_ts = 82.206390
current_seq = 734
count = 713
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.188087/733, current 82.207077/734: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.207077
last_received_ts = 82.206390
last_seq = 734
current_ts = 82.226067
current_received_ts = 82.225380
current_seq = 735
count = 714
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.207077/734, current 82.226067/735: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.226067
last_received_ts = 82.225380
last_seq = 735
current_ts = 82.245056
current_received_ts = 82.244362
current_seq = 736
count = 715
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.226067/735, current 82.245056/736: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.245056
last_received_ts = 82.244362
last_seq = 736
current_ts = 82.264046
current_received_ts = 82.263351
current_seq = 737
count = 716
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.245056/736, current 82.264046/737: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.264046
last_received_ts = 82.263351
last_seq = 737
current_ts = 82.283035
current_received_ts = 82.282341
current_seq = 738
count = 717
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.264046/737, current 82.283035/738: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.283035
last_received_ts = 82.282341
last_seq = 738
current_ts = 82.302017
current_received_ts = 82.301331
current_seq = 739
count = 718
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.283035/738, current 82.302017/739: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.302017
last_received_ts = 82.301331
last_seq = 739
current_ts = 82.321007
current_received_ts = 82.320320
current_seq = 740
count = 719
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.302017/739, current 82.321007/740: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.321007
last_received_ts = 82.320320
last_seq = 740
current_ts = 82.339996
current_received_ts = 82.339310
current_seq = 741
count = 720
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.321007/740, current 82.339996/741: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.339996
last_received_ts = 82.339310
last_seq = 741
current_ts = 82.358986
current_received_ts = 82.358292
current_seq = 742
count = 721
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.339996/741, current 82.358986/742: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.358986
last_received_ts = 82.358292
last_seq = 742
current_ts = 82.377975
current_received_ts = 82.377281
current_seq = 743
count = 722
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.358986/742, current 82.377975/743: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.377975
last_received_ts = 82.377281
last_seq = 743
current_ts = 82.396965
current_received_ts = 82.396271
current_seq = 744
count = 723
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.377975/743, current 82.396965/744: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.396965
last_received_ts = 82.396271
last_seq = 744
current_ts = 82.415947
current_received_ts = 82.415260
current_seq = 745
count = 724
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.396965/744, current 82.415947/745: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.415947
last_received_ts = 82.415260
last_seq = 745
current_ts = 82.434937
current_received_ts = 82.434250
current_seq = 746
count = 725
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.415947/745, current 82.434937/746: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.434937
last_received_ts = 82.434250
last_seq = 746
current_ts = 82.453926
current_received_ts = 82.453232
current_seq = 747
count = 726
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.434937/746, current 82.453926/747: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.453926
last_received_ts = 82.453232
last_seq = 747
current_ts = 82.472916
current_received_ts = 82.472221
current_seq = 748
count = 727
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.453926/747, current 82.472916/748: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.472916
last_received_ts = 82.472221
last_seq = 748
current_ts = 82.491905
current_received_ts = 82.491211
current_seq = 749
count = 728
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.472916/748, current 82.491905/749: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.491905
last_received_ts = 82.491211
last_seq = 749
current_ts = 82.510895
current_received_ts = 82.510201
current_seq = 750
count = 729
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.491905/749, current 82.510895/750: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.510895
last_received_ts = 82.510201
last_seq = 750
current_ts = 82.529877
current_received_ts = 82.529190
current_seq = 751
count = 730
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.510895/750, current 82.529877/751: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.529877
last_received_ts = 82.529190
last_seq = 751
current_ts = 82.548866
current_received_ts = 82.548180
current_seq = 752
count = 731
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.529877/751, current 82.548866/752: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.548866
last_received_ts = 82.548180
last_seq = 752
current_ts = 82.567856
current_received_ts = 82.567162
current_seq = 753
count = 732
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.548866/752, current 82.567856/753: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.567856
last_received_ts = 82.567162
last_seq = 753
current_ts = 82.586845
current_received_ts = 82.586151
current_seq = 754
count = 733
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.567856/753, current 82.586845/754: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.586845
last_received_ts = 82.586151
last_seq = 754
current_ts = 82.605827
current_received_ts = 82.605141
current_seq = 755
count = 734
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.586845/754, current 82.605827/755: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.605827
last_received_ts = 82.605141
last_seq = 755
current_ts = 82.624817
current_received_ts = 82.624130
current_seq = 756
count = 735
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.605827/755, current 82.624817/756: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.624817
last_received_ts = 82.624130
last_seq = 756
current_ts = 82.643806
current_received_ts = 82.643120
current_seq = 757
count = 736
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.624817/756, current 82.643806/757: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.643806
last_received_ts = 82.643120
last_seq = 757
current_ts = 82.662796
current_received_ts = 82.662109
current_seq = 758
count = 737
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.643806/757, current 82.662796/758: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.662796
last_received_ts = 82.662109
last_seq = 758
current_ts = 82.681786
current_received_ts = 82.681099
current_seq = 759
count = 738
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.662796/758, current 82.681786/759: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.681786
last_received_ts = 82.681099
last_seq = 759
current_ts = 82.700768
current_received_ts = 82.700081
current_seq = 760
count = 739
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.681786/759, current 82.700768/760: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.700768
last_received_ts = 82.700081
last_seq = 760
current_ts = 82.719765
current_received_ts = 82.719078
current_seq = 761
count = 740
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.700768/760, current 82.719765/761: elapsed=18996.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.719765
last_received_ts = 82.719078
last_seq = 761
current_ts = 82.738747
current_received_ts = 82.738060
current_seq = 762
count = 741
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.719765/761, current 82.738747/762: elapsed=18981.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.738747
last_received_ts = 82.738060
last_seq = 762
current_ts = 82.757736
current_received_ts = 82.757050
current_seq = 763
count = 742
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.738747/762, current 82.757736/763: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.757736
last_received_ts = 82.757050
last_seq = 763
current_ts = 82.776726
current_received_ts = 82.776047
current_seq = 764
count = 743
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.757736/763, current 82.776726/764: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.776726
last_received_ts = 82.776047
last_seq = 764
current_ts = 82.795715
current_received_ts = 82.795044
current_seq = 765
count = 744
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.776726/764, current 82.795715/765: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.795715
last_received_ts = 82.795044
last_seq = 765
current_ts = 82.814697
current_received_ts = 82.814026
current_seq = 766
count = 745
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.795715/765, current 82.814697/766: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.814697
last_received_ts = 82.814026
last_seq = 766
current_ts = 82.833687
current_received_ts = 82.833000
current_seq = 767
count = 746
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.814697/766, current 82.833687/767: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.833687
last_received_ts = 82.833000
last_seq = 767
current_ts = 82.852676
current_received_ts = 82.851982
current_seq = 768
count = 747
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.833687/767, current 82.852676/768: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.852676
last_received_ts = 82.851982
last_seq = 768
current_ts = 82.871666
current_received_ts = 82.870972
current_seq = 769
count = 748
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.852676/768, current 82.871666/769: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.871666
last_received_ts = 82.870972
last_seq = 769
current_ts = 82.890656
current_received_ts = 82.889961
current_seq = 770
count = 749
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.871666/769, current 82.890656/770: elapsed=18989.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.890656
last_received_ts = 82.889961
last_seq = 770
current_ts = 82.909637
current_received_ts = 82.908951
current_seq = 771
count = 750
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.890656/770, current 82.909637/771: elapsed=18988.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.909637
last_received_ts = 82.908951
last_seq = 771
current_ts = 82.928627
current_received_ts = 82.927940
current_seq = 772
count = 751
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.909637/771, current 82.928627/772: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.928627
last_received_ts = 82.927940
last_seq = 772
current_ts = 82.947617
current_received_ts = 82.946922
current_seq = 773
count = 752
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.928627/772, current 82.947617/773: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.947617
last_received_ts = 82.946922
last_seq = 773
current_ts = 82.966599
current_received_ts = 82.965912
current_seq = 774
count = 753
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.947617/773, current 82.966599/774: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.966599
last_received_ts = 82.965912
last_seq = 774
current_ts = 82.985588
current_received_ts = 82.984894
current_seq = 775
count = 754
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.966599/774, current 82.985588/775: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.985588
last_received_ts = 82.984894
last_seq = 775
current_ts = 83.004570
current_received_ts = 83.003883
current_seq = 776
count = 755
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.985588/775, current 83.004570/776: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.004570
last_received_ts = 83.003883
last_seq = 776
current_ts = 83.023560
current_received_ts = 83.022865
current_seq = 777
count = 756
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.004570/776, current 83.023560/777: elapsed=18985.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.023560
last_received_ts = 83.022865
last_seq = 777
current_ts = 83.042542
current_received_ts = 83.041855
current_seq = 778
count = 757
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.023560/777, current 83.042542/778: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.042542
last_received_ts = 83.041855
last_seq = 778
current_ts = 83.061531
current_received_ts = 83.060844
current_seq = 779
count = 758
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.042542/778, current 83.061531/779: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.061531
last_received_ts = 83.060844
last_seq = 779
current_ts = 83.080521
current_received_ts = 83.079826
current_seq = 780
count = 759
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.061531/779, current 83.080521/780: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.080521
last_received_ts = 83.079826
last_seq = 780
current_ts = 83.099503
current_received_ts = 83.098816
current_seq = 781
count = 760
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.080521/780, current 83.099503/781: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.099503
last_received_ts = 83.098816
last_seq = 781
current_ts = 83.118492
current_received_ts = 83.117798
current_seq = 782
count = 761
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.099503/781, current 83.118492/782: elapsed=18985.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.118492
last_received_ts = 83.117798
last_seq = 782
current_ts = 83.137474
current_received_ts = 83.136787
current_seq = 783
count = 762
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.118492/782, current 83.137474/783: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.137474
last_received_ts = 83.136787
last_seq = 783
current_ts = 83.156464
current_received_ts = 83.155769
current_seq = 784
count = 763
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.137474/783, current 83.156464/784: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.156464
last_received_ts = 83.155769
last_seq = 784
current_ts = 83.175453
current_received_ts = 83.174759
current_seq = 785
count = 764
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.156464/784, current 83.175453/785: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.175453
last_received_ts = 83.174759
last_seq = 785
current_ts = 83.194435
current_received_ts = 83.193748
current_seq = 786
count = 765
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.175453/785, current 83.194435/786: elapsed=18985.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.194435
last_received_ts = 83.193748
last_seq = 786
current_ts = 83.213425
current_received_ts = 83.212730
current_seq = 787
count = 766
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.194435/786, current 83.213425/787: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.213425
last_received_ts = 83.212730
last_seq = 787
current_ts = 83.232407
current_received_ts = 83.231720
current_seq = 788
count = 767
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.213425/787, current 83.232407/788: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.232407
last_received_ts = 83.231720
last_seq = 788
current_ts = 83.251396
current_received_ts = 83.250702
current_seq = 789
count = 768
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.232407/788, current 83.251396/789: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.251396
last_received_ts = 83.250702
last_seq = 789
current_ts = 83.270378
current_received_ts = 83.269691
current_seq = 790
count = 769
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.251396/789, current 83.270378/790: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.270378
last_received_ts = 83.269691
last_seq = 790
current_ts = 83.289368
current_received_ts = 83.288673
current_seq = 791
count = 770
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.270378/790, current 83.289368/791: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.289368
last_received_ts = 83.288673
last_seq = 791
current_ts = 83.308357
current_received_ts = 83.307663
current_seq = 792
count = 771
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.289368/791, current 83.308357/792: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.308357
last_received_ts = 83.307663
last_seq = 792
current_ts = 83.327339
current_received_ts = 83.326653
current_seq = 793
count = 772
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.308357/792, current 83.327339/793: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.327339
last_received_ts = 83.326653
last_seq = 793
current_ts = 83.346329
current_received_ts = 83.345634
current_seq = 794
count = 773
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.327339/793, current 83.346329/794: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.346329
last_received_ts = 83.345634
last_seq = 794
current_ts = 83.365311
current_received_ts = 83.364624
current_seq = 795
count = 774
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.346329/794, current 83.365311/795: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.365311
last_received_ts = 83.364624
last_seq = 795
current_ts = 83.384300
current_received_ts = 83.383606
current_seq = 796
count = 775
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.365311/795, current 83.384300/796: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.384300
last_received_ts = 83.383606
last_seq = 796
current_ts = 83.403290
current_received_ts = 83.402596
current_seq = 797
count = 776
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.384300/796, current 83.403290/797: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.403290
last_received_ts = 83.402596
last_seq = 797
current_ts = 83.422272
current_received_ts = 83.421585
current_seq = 798
count = 777
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.403290/797, current 83.422272/798: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.422272
last_received_ts = 83.421585
last_seq = 798
current_ts = 83.441261
current_received_ts = 83.440567
current_seq = 799
count = 778
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.422272/798, current 83.441261/799: elapsed=18985.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.441261
last_received_ts = 83.440567
last_seq = 799
current_ts = 83.460243
current_received_ts = 83.459557
current_seq = 800
count = 779
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.441261/799, current 83.460243/800: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.460243
last_received_ts = 83.459557
last_seq = 800
current_ts = 83.479233
current_received_ts = 83.478539
current_seq = 801
count = 780
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.460243/800, current 83.479233/801: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.479233
last_received_ts = 83.478539
last_seq = 801
current_ts = 83.498215
current_received_ts = 83.497528
current_seq = 802
count = 781
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.479233/801, current 83.498215/802: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.498215
last_received_ts = 83.497528
last_seq = 802
current_ts = 83.517204
current_received_ts = 83.516510
current_seq = 803
count = 782
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.498215/802, current 83.517204/803: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.517204
last_received_ts = 83.516510
last_seq = 803
current_ts = 83.536186
current_received_ts = 83.535500
current_seq = 804
count = 783
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.517204/803, current 83.536186/804: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.536186
last_received_ts = 83.535500
last_seq = 804
current_ts = 83.555176
current_received_ts = 83.554489
current_seq = 805
count = 784
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.536186/804, current 83.555176/805: elapsed=18986.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.555176
last_received_ts = 83.554489
last_seq = 805
current_ts = 83.574165
current_received_ts = 83.573471
current_seq = 806
count = 785
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.555176/805, current 83.574165/806: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.574165
last_received_ts = 83.573471
last_seq = 806
current_ts = 83.593147
current_received_ts = 83.592461
current_seq = 807
count = 786
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.574165/806, current 83.593147/807: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.593147
last_received_ts = 83.592461
last_seq = 807
current_ts = 83.612137
current_received_ts = 83.611443
current_seq = 808
count = 787
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.593147/807, current 83.612137/808: elapsed=18985.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.612137
last_received_ts = 83.611443
last_seq = 808
current_ts = 83.631119
current_received_ts = 83.630432
current_seq = 809
count = 788
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.612137/808, current 83.631119/809: elapsed=18987.0us expected=19066.1us +- 95.3us, error 0.4%
(kms_flip:2073) DEBUG: expected 786, counted 789, encoder type 2
(kms_flip:2073) DEBUG: dropped frames, expected 786, counted 789, encoder type 2
(kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2073) igt_core-INFO: Stack trace:
(kms_flip:2073) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2073) igt_core-INFO:   #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0()
(kms_flip:2073) igt_core-INFO:   #2 ../tests/kms_flip.c:1831 run_test()
(kms_flip:2073) igt_core-INFO:   #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
(kms_flip:2073) igt_core-INFO:   #4 ../tests/kms_flip.c:1987 main()
(kms_flip:2073) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2073) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2073) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A2: FAIL (18.640s)
Subtest plain-flip-ts-check: FAIL (25.320s)
(kms_flip:2073) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246:
(kms_flip:2073) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2073) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2073) igt_kms-CRITICAL: DPMS property not found on 147
Dmesg
<6> [68.024087] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [68.025132] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:166]
<7> [68.025370] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167]
<7> [68.077365] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [68.077527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [68.077982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [68.078444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [68.078913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [68.079418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [68.079846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [68.080226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [68.080611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [68.080966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [68.081354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [68.081763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [68.082138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [68.082528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [68.082844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [68.083153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [68.083474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [68.083757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [68.084055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [68.084368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [68.084649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [68.084928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [68.085236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [68.085548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [68.085798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [68.086009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [68.086235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [68.086437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [68.086637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [68.086839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [68.087056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [68.087296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [68.087504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [68.087710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [68.087916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [68.088153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [68.088384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [68.088586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [68.088779] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [68.088935] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [68.089135] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [68.089300] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [68.089487] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [68.089640] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [68.089790] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [68.089939] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.090105] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.090273] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.090422] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [68.090578] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [68.090749] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [68.090905] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [68.091072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [68.091253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [68.091405] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [68.091556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [68.091712] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [68.091852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [68.092003] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [68.111214] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [68.130404] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [68.130996] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [68.131554] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [68.132183] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [68.132829] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [68.133440] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [68.134080] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [68.134669] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [68.135259] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [68.135896] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [68.136614] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [68.137356] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [68.137968] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [68.138981] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [68.139358] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [68.139411] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:147:HDMI-A-2]
<7> [68.139549] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:147:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [68.140061] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [68.140480] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [68.140896] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:146:DDI TC1/PHY C] [CRTC:134:pipe B]
<7> [68.141248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [68.141611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [68.142001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [68.142354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [68.142735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [68.143073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [68.143412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [68.143738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [68.143995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [68.144252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [68.144509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [68.144790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [68.145047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [68.145302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [68.145557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [68.145830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [68.146065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [68.146299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [68.146540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [68.146744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [68.146941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [68.147133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [68.147325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [68.147516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [68.147715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [68.147906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [68.148096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [68.148286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [68.148477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [68.148676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [68.148840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [68.149003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [68.149167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [68.149330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state
<7> [68.149494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.149657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [68.149827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.149973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [68.150117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [68.150260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [68.150401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [68.150542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [68.150688] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [68.150847] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [68.150989] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [68.151159] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [68.151300] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [68.151440] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [68.151578] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [68.151727] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [68.151866] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [68.152007] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [68.152153] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [68.152295] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [68.152447] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:84:plane 1B] min cdclk (69965 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz)
<7> [68.152589] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [68.152753] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [68.152897] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [68.153040] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [68.153184] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [68.153328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [68.153470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [68.153611] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [68.153766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [68.153909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [68.154054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [68.154197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [68.154341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [68.154483] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [68.154623] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [68.154787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [68.154932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [68.155074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [68.155215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [68.155356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [68.155496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [68.155643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [68.155783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [68.155922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [68.156059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [68.156197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [68.156336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [68.156474] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [68.156614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [68.156771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [68.156913] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [68.157054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.157195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.157335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.157475] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [68.157614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [68.157767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.157907] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.158045] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.158184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [68.158324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:166] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [68.158465] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [68.158603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [68.158753] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [68.158891] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [68.159030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [68.159168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [68.159307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [68.159873] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [68.160043] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [68.160187] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [68.160328] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [68.160467] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [68.160606] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [68.160785] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [68.160961] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [68.161106] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [68.161641] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [68.161823] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [68.181624] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [68.181814] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [86.663992] [IGT] kms_flip: finished subtest B-HDMI-A2, FAIL
<6> [86.665452] [IGT] kms_flip: finished subtest plain-flip-ts-check, FAIL
<7> [86.688249] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:166] from all active usage due to RMFB ioctl
<7> [86.688473] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:166] is removed
<7> [86.688538] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [86.688709] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [86.688851] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [86.688992] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [86.689132] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [86.689270] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [86.689426] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [86.723400] [IGT] kms_flip: exiting, ret=98
<7> [86.734443] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:147:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [86.734632] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [86.734773] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [86.734931] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:146:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [86.735072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [86.735216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [86.735370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [86.735511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [86.735652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [86.735797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [86.735941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [86.736085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [86.736226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [86.736378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [86.736524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [86.736669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [86.736811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [86.736953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [86.737094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [86.737236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [86.737385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [86.737525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [86.737665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [86.737805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [86.737944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [86.738084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [86.738222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [86.738370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [86.738510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [86.738649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [86.738789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [86.738929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [86.739069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [86.739208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [86.739355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [86.739493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [86.739632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [86.739773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [86.739912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [86.740051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [86.740191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [86.740337] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [86.740478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [86.740618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [86.740758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [86.740898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [86.741037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [86.741177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [86.741321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [86.741460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [86.741600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [86.741739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [86.741879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [86.742017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [86.742157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [86.742300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [86.742439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [86.742579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [86.742718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [86.742858] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [86.742997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [86.743135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [86.743274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [86.743502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [86.743642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [86.743782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [86.743922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [86.744062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [86.744201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [86.744361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [86.744503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [86.744648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [86.744791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [86.744933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [86.745074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [86.745216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [86.745376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [86.745517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [86.745657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [86.745797] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [86.745942] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [86.746259] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x1
<7> [86.746411] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x1
<7> [86.746567] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [86.746707] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [86.746849] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [86.746990] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [86.747129] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [86.747268] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [86.747484] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [86.747627] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [86.748326] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [86.748480] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [86.748643] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [86.748792] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [86.748935] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [86.749077] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [86.749223] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [86.749385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [86.749530] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [86.749672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [86.749817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [86.749960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [86.750102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [86.750242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [86.750391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [86.750532] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [86.750673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [86.750817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [86.750961] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [86.751101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [86.751242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [86.751391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [86.751531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [86.751671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [86.751811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [86.751950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [86.752094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [86.752236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [86.752385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [86.752526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [86.752667] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [86.752808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [86.752947] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [86.753086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.753226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.753373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.753512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [86.753650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [86.753788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.753927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.754071] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.754213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [86.754369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:162] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [86.754512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [86.754653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [86.754794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [86.754934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [86.755079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [86.755223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [86.755372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [86.755514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [86.755655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [86.755796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [86.755935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [86.756074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [86.756213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [86.756360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [86.756877] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [86.777301] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [86.777560] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [86.777777] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [86.777932] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [86.778077] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [86.778219] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [86.778371] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [86.778512] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [86.778741] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [86.778889] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [86.779465] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [86.779666] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [86.780171] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [86.780336] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [86.799261] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [86.799646] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [86.800419] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [86.801401] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2025-03-22 18:32:33