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

Machine description: shard-rkl-7

Result: Fail

git-log-oneline i915_display_info8 igt_runner8 run8 runtimes8 results8.json boot8 dmesg8

DetailValue
Duration 18.49 seconds
Hostname 
shard-rkl-7
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc7-CI_DRM_15689-g169c7cd31a37+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A1
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19050.0us +- 7.457us accuracy 0.12%
vblank interval differs from modeline! expected 18973.8us, measured 19050us +- 7.457us, difference 76.2us (10.2 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A1: FAIL (18.489s)
Subtest plain-flip-ts-check-interruptible: FAIL (25.415s)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2234 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2930 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2386 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: B-HDMI-A1
(kms_flip:1258) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1258) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1258) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:1258) DEBUG: flip ts/seq: last 72.479965/689, current 72.498955/690: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.498955
last_received_ts = 72.498253
last_seq = 690
current_ts = 72.517944
current_received_ts = 72.517242
current_seq = 691
count = 670
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.498955/690, current 72.517944/691: elapsed=18989.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.517944
last_received_ts = 72.517242
last_seq = 691
current_ts = 72.536934
current_received_ts = 72.536224
current_seq = 692
count = 671
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.517944/691, current 72.536934/692: elapsed=18988.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.536934
last_received_ts = 72.536224
last_seq = 692
current_ts = 72.555916
current_received_ts = 72.555222
current_seq = 693
count = 672
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.536934/692, current 72.555916/693: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.555916
last_received_ts = 72.555222
last_seq = 693
current_ts = 72.574905
current_received_ts = 72.574203
current_seq = 694
count = 673
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.555916/693, current 72.574905/694: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.574905
last_received_ts = 72.574203
last_seq = 694
current_ts = 72.593895
current_received_ts = 72.593193
current_seq = 695
count = 674
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.574905/694, current 72.593895/695: elapsed=18989.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.593895
last_received_ts = 72.593193
last_seq = 695
current_ts = 72.612885
current_received_ts = 72.612175
current_seq = 696
count = 675
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.593895/695, current 72.612885/696: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.612885
last_received_ts = 72.612175
last_seq = 696
current_ts = 72.631882
current_received_ts = 72.631165
current_seq = 697
count = 676
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.612885/696, current 72.631882/697: elapsed=19002.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.631882
last_received_ts = 72.631165
last_seq = 697
current_ts = 72.650856
current_received_ts = 72.650154
current_seq = 698
count = 677
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.631882/697, current 72.650856/698: elapsed=18971.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.650856
last_received_ts = 72.650154
last_seq = 698
current_ts = 72.669838
current_received_ts = 72.669136
current_seq = 699
count = 678
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.650856/698, current 72.669838/699: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.669838
last_received_ts = 72.669136
last_seq = 699
current_ts = 72.688843
current_received_ts = 72.688133
current_seq = 700
count = 679
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.669838/699, current 72.688843/700: elapsed=19004.0us expected=19050.0us +- 95.3us, error 0.2%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.688843
last_received_ts = 72.688133
last_seq = 700
current_ts = 72.707832
current_received_ts = 72.707115
current_seq = 701
count = 680
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.688843/700, current 72.707832/701: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.707832
last_received_ts = 72.707115
last_seq = 701
current_ts = 72.726807
current_received_ts = 72.726097
current_seq = 702
count = 681
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.707832/701, current 72.726807/702: elapsed=18971.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.726807
last_received_ts = 72.726097
last_seq = 702
current_ts = 72.745789
current_received_ts = 72.745087
current_seq = 703
count = 682
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.726807/702, current 72.745789/703: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.745789
last_received_ts = 72.745087
last_seq = 703
current_ts = 72.764778
current_received_ts = 72.764076
current_seq = 704
count = 683
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.745789/703, current 72.764778/704: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.764778
last_received_ts = 72.764076
last_seq = 704
current_ts = 72.783783
current_received_ts = 72.783058
current_seq = 705
count = 684
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.764778/704, current 72.783783/705: elapsed=19004.0us expected=19050.0us +- 95.3us, error 0.2%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.783783
last_received_ts = 72.783058
last_seq = 705
current_ts = 72.802765
current_received_ts = 72.802048
current_seq = 706
count = 685
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.783783/705, current 72.802765/706: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.802765
last_received_ts = 72.802048
last_seq = 706
current_ts = 72.821754
current_received_ts = 72.821037
current_seq = 707
count = 686
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.802765/706, current 72.821754/707: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.821754
last_received_ts = 72.821037
last_seq = 707
current_ts = 72.840729
current_received_ts = 72.840034
current_seq = 708
count = 687
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.821754/707, current 72.840729/708: elapsed=18970.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.840729
last_received_ts = 72.840034
last_seq = 708
current_ts = 72.859726
current_received_ts = 72.859032
current_seq = 709
count = 688
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.840729/708, current 72.859726/709: elapsed=19001.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.859726
last_received_ts = 72.859032
last_seq = 709
current_ts = 72.878716
current_received_ts = 72.877991
current_seq = 710
count = 689
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.859726/709, current 72.878716/710: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.878716
last_received_ts = 72.877991
last_seq = 710
current_ts = 72.897697
current_received_ts = 72.896980
current_seq = 711
count = 690
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.878716/710, current 72.897697/711: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.897697
last_received_ts = 72.896980
last_seq = 711
current_ts = 72.916664
current_received_ts = 72.915962
current_seq = 712
count = 691
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.897697/711, current 72.916664/712: elapsed=18969.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.916664
last_received_ts = 72.915962
last_seq = 712
current_ts = 72.935669
current_received_ts = 72.934952
current_seq = 713
count = 692
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.916664/712, current 72.935669/713: elapsed=19001.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.935669
last_received_ts = 72.934952
last_seq = 713
current_ts = 72.954651
current_received_ts = 72.953934
current_seq = 714
count = 693
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.935669/713, current 72.954651/714: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.954651
last_received_ts = 72.953934
last_seq = 714
current_ts = 72.973640
current_received_ts = 72.972923
current_seq = 715
count = 694
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.954651/714, current 72.973640/715: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.973640
last_received_ts = 72.972923
last_seq = 715
current_ts = 72.992622
current_received_ts = 72.991905
current_seq = 716
count = 695
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.973640/715, current 72.992622/716: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 72.992622
last_received_ts = 72.991905
last_seq = 716
current_ts = 73.011597
current_received_ts = 73.010895
current_seq = 717
count = 696
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 72.992622/716, current 73.011597/717: elapsed=18969.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.011597
last_received_ts = 73.010895
last_seq = 717
current_ts = 73.030594
current_received_ts = 73.029877
current_seq = 718
count = 697
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.011597/717, current 73.030594/718: elapsed=19002.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.030594
last_received_ts = 73.029877
last_seq = 718
current_ts = 73.049583
current_received_ts = 73.048866
current_seq = 719
count = 698
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.030594/718, current 73.049583/719: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.049583
last_received_ts = 73.048866
last_seq = 719
current_ts = 73.068550
current_received_ts = 73.067848
current_seq = 720
count = 699
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.049583/719, current 73.068550/720: elapsed=18969.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.068550
last_received_ts = 73.067848
last_seq = 720
current_ts = 73.087540
current_received_ts = 73.086838
current_seq = 721
count = 700
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.068550/720, current 73.087540/721: elapsed=18988.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.087540
last_received_ts = 73.086838
last_seq = 721
current_ts = 73.106522
current_received_ts = 73.105820
current_seq = 722
count = 701
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.087540/721, current 73.106522/722: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.106522
last_received_ts = 73.105820
last_seq = 722
current_ts = 73.125511
current_received_ts = 73.124809
current_seq = 723
count = 702
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.106522/722, current 73.125511/723: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.125511
last_received_ts = 73.124809
last_seq = 723
current_ts = 73.144493
current_received_ts = 73.143791
current_seq = 724
count = 703
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.125511/723, current 73.144493/724: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.144493
last_received_ts = 73.143791
last_seq = 724
current_ts = 73.163483
current_received_ts = 73.162781
current_seq = 725
count = 704
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.144493/724, current 73.163483/725: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.163483
last_received_ts = 73.162781
last_seq = 725
current_ts = 73.182465
current_received_ts = 73.181763
current_seq = 726
count = 705
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.163483/725, current 73.182465/726: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.182465
last_received_ts = 73.181763
last_seq = 726
current_ts = 73.201447
current_received_ts = 73.200745
current_seq = 727
count = 706
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.182465/726, current 73.201447/727: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.201447
last_received_ts = 73.200745
last_seq = 727
current_ts = 73.220436
current_received_ts = 73.219734
current_seq = 728
count = 707
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.201447/727, current 73.220436/728: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.220436
last_received_ts = 73.219734
last_seq = 728
current_ts = 73.239426
current_received_ts = 73.238724
current_seq = 729
count = 708
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.220436/728, current 73.239426/729: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.239426
last_received_ts = 73.238724
last_seq = 729
current_ts = 73.258408
current_received_ts = 73.257706
current_seq = 730
count = 709
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.239426/729, current 73.258408/730: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.258408
last_received_ts = 73.257706
last_seq = 730
current_ts = 73.277390
current_received_ts = 73.276688
current_seq = 731
count = 710
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.258408/730, current 73.277390/731: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.277390
last_received_ts = 73.276688
last_seq = 731
current_ts = 73.296379
current_received_ts = 73.295677
current_seq = 732
count = 711
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.277390/731, current 73.296379/732: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.296379
last_received_ts = 73.295677
last_seq = 732
current_ts = 73.315369
current_received_ts = 73.314667
current_seq = 733
count = 712
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.296379/732, current 73.315369/733: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.315369
last_received_ts = 73.314667
last_seq = 733
current_ts = 73.334351
current_received_ts = 73.333649
current_seq = 734
count = 713
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.315369/733, current 73.334351/734: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.334351
last_received_ts = 73.333649
last_seq = 734
current_ts = 73.353333
current_received_ts = 73.352631
current_seq = 735
count = 714
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.334351/734, current 73.353333/735: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.353333
last_received_ts = 73.352631
last_seq = 735
current_ts = 73.372337
current_received_ts = 73.371628
current_seq = 736
count = 715
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.353333/735, current 73.372337/736: elapsed=19000.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.372337
last_received_ts = 73.371628
last_seq = 736
current_ts = 73.391304
current_received_ts = 73.390602
current_seq = 737
count = 716
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.372337/736, current 73.391304/737: elapsed=18970.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.391304
last_received_ts = 73.390602
last_seq = 737
current_ts = 73.410294
current_received_ts = 73.409584
current_seq = 738
count = 717
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.391304/737, current 73.410294/738: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.410294
last_received_ts = 73.409584
last_seq = 738
current_ts = 73.429276
current_received_ts = 73.428574
current_seq = 739
count = 718
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.410294/738, current 73.429276/739: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.429276
last_received_ts = 73.428574
last_seq = 739
current_ts = 73.448257
current_received_ts = 73.447556
current_seq = 740
count = 719
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.429276/739, current 73.448257/740: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.448257
last_received_ts = 73.447556
last_seq = 740
current_ts = 73.467247
current_received_ts = 73.466545
current_seq = 741
count = 720
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.448257/740, current 73.467247/741: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.467247
last_received_ts = 73.466545
last_seq = 741
current_ts = 73.486237
current_received_ts = 73.485535
current_seq = 742
count = 721
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.467247/741, current 73.486237/742: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.486237
last_received_ts = 73.485535
last_seq = 742
current_ts = 73.505219
current_received_ts = 73.504517
current_seq = 743
count = 722
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.486237/742, current 73.505219/743: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.505219
last_received_ts = 73.504517
last_seq = 743
current_ts = 73.524200
current_received_ts = 73.523499
current_seq = 744
count = 723
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.505219/743, current 73.524200/744: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.524200
last_received_ts = 73.523499
last_seq = 744
current_ts = 73.543190
current_received_ts = 73.542488
current_seq = 745
count = 724
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.524200/744, current 73.543190/745: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.543190
last_received_ts = 73.542488
last_seq = 745
current_ts = 73.562172
current_received_ts = 73.561470
current_seq = 746
count = 725
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.543190/745, current 73.562172/746: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.562172
last_received_ts = 73.561470
last_seq = 746
current_ts = 73.581161
current_received_ts = 73.580460
current_seq = 747
count = 726
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.562172/746, current 73.581161/747: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.581161
last_received_ts = 73.580460
last_seq = 747
current_ts = 73.600151
current_received_ts = 73.599449
current_seq = 748
count = 727
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.581161/747, current 73.600151/748: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.600151
last_received_ts = 73.599449
last_seq = 748
current_ts = 73.619133
current_received_ts = 73.618431
current_seq = 749
count = 728
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.600151/748, current 73.619133/749: elapsed=18988.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.619133
last_received_ts = 73.618431
last_seq = 749
current_ts = 73.638115
current_received_ts = 73.637413
current_seq = 750
count = 729
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.619133/749, current 73.638115/750: elapsed=18981.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.638115
last_received_ts = 73.637413
last_seq = 750
current_ts = 73.657104
current_received_ts = 73.656403
current_seq = 751
count = 730
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.638115/750, current 73.657104/751: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.657104
last_received_ts = 73.656403
last_seq = 751
current_ts = 73.676086
current_received_ts = 73.675385
current_seq = 752
count = 731
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.657104/751, current 73.676086/752: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.676086
last_received_ts = 73.675385
last_seq = 752
current_ts = 73.695076
current_received_ts = 73.694366
current_seq = 753
count = 732
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.676086/752, current 73.695076/753: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.695076
last_received_ts = 73.694366
last_seq = 753
current_ts = 73.714058
current_received_ts = 73.713356
current_seq = 754
count = 733
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.695076/753, current 73.714058/754: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.714058
last_received_ts = 73.713356
last_seq = 754
current_ts = 73.733047
current_received_ts = 73.732346
current_seq = 755
count = 734
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.714058/754, current 73.733047/755: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.733047
last_received_ts = 73.732346
last_seq = 755
current_ts = 73.752029
current_received_ts = 73.751328
current_seq = 756
count = 735
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.733047/755, current 73.752029/756: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.752029
last_received_ts = 73.751328
last_seq = 756
current_ts = 73.771019
current_received_ts = 73.770317
current_seq = 757
count = 736
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.752029/756, current 73.771019/757: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.771019
last_received_ts = 73.770317
last_seq = 757
current_ts = 73.790001
current_received_ts = 73.789299
current_seq = 758
count = 737
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.771019/757, current 73.790001/758: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.790001
last_received_ts = 73.789299
last_seq = 758
current_ts = 73.808983
current_received_ts = 73.808289
current_seq = 759
count = 738
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.790001/758, current 73.808983/759: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.808983
last_received_ts = 73.808289
last_seq = 759
current_ts = 73.827972
current_received_ts = 73.827271
current_seq = 760
count = 739
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.808983/759, current 73.827972/760: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.827972
last_received_ts = 73.827271
last_seq = 760
current_ts = 73.846954
current_received_ts = 73.846252
current_seq = 761
count = 740
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.827972/760, current 73.846954/761: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.846954
last_received_ts = 73.846252
last_seq = 761
current_ts = 73.865936
current_received_ts = 73.865234
current_seq = 762
count = 741
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.846954/761, current 73.865936/762: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.865936
last_received_ts = 73.865234
last_seq = 762
current_ts = 73.884926
current_received_ts = 73.884224
current_seq = 763
count = 742
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.865936/762, current 73.884926/763: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.884926
last_received_ts = 73.884224
last_seq = 763
current_ts = 73.903908
current_received_ts = 73.903206
current_seq = 764
count = 743
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.884926/763, current 73.903908/764: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.903908
last_received_ts = 73.903206
last_seq = 764
current_ts = 73.922890
current_received_ts = 73.922188
current_seq = 765
count = 744
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.903908/764, current 73.922890/765: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.922890
last_received_ts = 73.922188
last_seq = 765
current_ts = 73.941879
current_received_ts = 73.941170
current_seq = 766
count = 745
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.922890/765, current 73.941879/766: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.941879
last_received_ts = 73.941170
last_seq = 766
current_ts = 73.960861
current_received_ts = 73.960159
current_seq = 767
count = 746
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.941879/766, current 73.960861/767: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.960861
last_received_ts = 73.960159
last_seq = 767
current_ts = 73.979843
current_received_ts = 73.979141
current_seq = 768
count = 747
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.960861/767, current 73.979843/768: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.979843
last_received_ts = 73.979141
last_seq = 768
current_ts = 73.998825
current_received_ts = 73.998123
current_seq = 769
count = 748
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.979843/768, current 73.998825/769: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 73.998825
last_received_ts = 73.998123
last_seq = 769
current_ts = 74.017815
current_received_ts = 74.017113
current_seq = 770
count = 749
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 73.998825/769, current 74.017815/770: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.017815
last_received_ts = 74.017113
last_seq = 770
current_ts = 74.036797
current_received_ts = 74.036095
current_seq = 771
count = 750
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.017815/770, current 74.036797/771: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.036797
last_received_ts = 74.036095
last_seq = 771
current_ts = 74.055779
current_received_ts = 74.055077
current_seq = 772
count = 751
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.036797/771, current 74.055779/772: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.055779
last_received_ts = 74.055077
last_seq = 772
current_ts = 74.074768
current_received_ts = 74.074066
current_seq = 773
count = 752
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.055779/772, current 74.074768/773: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.074768
last_received_ts = 74.074066
last_seq = 773
current_ts = 74.093750
current_received_ts = 74.093048
current_seq = 774
count = 753
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.074768/773, current 74.093750/774: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.093750
last_received_ts = 74.093048
last_seq = 774
current_ts = 74.112732
current_received_ts = 74.112030
current_seq = 775
count = 754
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.093750/774, current 74.112732/775: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.112732
last_received_ts = 74.112030
last_seq = 775
current_ts = 74.131714
current_received_ts = 74.131050
current_seq = 776
count = 755
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.112732/775, current 74.131714/776: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.131714
last_received_ts = 74.131050
last_seq = 776
current_ts = 74.150703
current_received_ts = 74.150002
current_seq = 777
count = 756
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.131714/776, current 74.150703/777: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.150703
last_received_ts = 74.150002
last_seq = 777
current_ts = 74.169685
current_received_ts = 74.168983
current_seq = 778
count = 757
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.150703/777, current 74.169685/778: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.169685
last_received_ts = 74.168983
last_seq = 778
current_ts = 74.188667
current_received_ts = 74.187965
current_seq = 779
count = 758
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.169685/778, current 74.188667/779: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.188667
last_received_ts = 74.187965
last_seq = 779
current_ts = 74.207657
current_received_ts = 74.206955
current_seq = 780
count = 759
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.188667/779, current 74.207657/780: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.207657
last_received_ts = 74.206955
last_seq = 780
current_ts = 74.226639
current_received_ts = 74.225937
current_seq = 781
count = 760
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.207657/780, current 74.226639/781: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.226639
last_received_ts = 74.225937
last_seq = 781
current_ts = 74.245621
current_received_ts = 74.244919
current_seq = 782
count = 761
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.226639/781, current 74.245621/782: elapsed=18982.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.245621
last_received_ts = 74.244919
last_seq = 782
current_ts = 74.264610
current_received_ts = 74.263908
current_seq = 783
count = 762
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.245621/782, current 74.264610/783: elapsed=18987.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.264610
last_received_ts = 74.263908
last_seq = 783
current_ts = 74.283592
current_received_ts = 74.282890
current_seq = 784
count = 763
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.264610/783, current 74.283592/784: elapsed=18981.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.283592
last_received_ts = 74.282890
last_seq = 784
current_ts = 74.302574
current_received_ts = 74.301872
current_seq = 785
count = 764
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.283592/784, current 74.302574/785: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.302574
last_received_ts = 74.301872
last_seq = 785
current_ts = 74.321556
current_received_ts = 74.320854
current_seq = 786
count = 765
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.302574/785, current 74.321556/786: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.321556
last_received_ts = 74.320854
last_seq = 786
current_ts = 74.340538
current_received_ts = 74.339836
current_seq = 787
count = 766
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.321556/786, current 74.340538/787: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.340538
last_received_ts = 74.339836
last_seq = 787
current_ts = 74.359528
current_received_ts = 74.358826
current_seq = 788
count = 767
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.340538/787, current 74.359528/788: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.359528
last_received_ts = 74.358826
last_seq = 788
current_ts = 74.378510
current_received_ts = 74.377808
current_seq = 789
count = 768
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.359528/788, current 74.378510/789: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.378510
last_received_ts = 74.377808
last_seq = 789
current_ts = 74.397491
current_received_ts = 74.396790
current_seq = 790
count = 769
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.378510/789, current 74.397491/790: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.397491
last_received_ts = 74.396790
last_seq = 790
current_ts = 74.416481
current_received_ts = 74.415779
current_seq = 791
count = 770
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.397491/790, current 74.416481/791: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.416481
last_received_ts = 74.415779
last_seq = 791
current_ts = 74.435463
current_received_ts = 74.434761
current_seq = 792
count = 771
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.416481/791, current 74.435463/792: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.435463
last_received_ts = 74.434761
last_seq = 792
current_ts = 74.454445
current_received_ts = 74.453743
current_seq = 793
count = 772
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.435463/792, current 74.454445/793: elapsed=18982.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.454445
last_received_ts = 74.453743
last_seq = 793
current_ts = 74.473434
current_received_ts = 74.472733
current_seq = 794
count = 773
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.454445/793, current 74.473434/794: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.473434
last_received_ts = 74.472733
last_seq = 794
current_ts = 74.492416
current_received_ts = 74.491714
current_seq = 795
count = 774
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.473434/794, current 74.492416/795: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.492416
last_received_ts = 74.491714
last_seq = 795
current_ts = 74.511398
current_received_ts = 74.510696
current_seq = 796
count = 775
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.492416/795, current 74.511398/796: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.511398
last_received_ts = 74.510696
last_seq = 796
current_ts = 74.530380
current_received_ts = 74.529678
current_seq = 797
count = 776
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.511398/796, current 74.530380/797: elapsed=18981.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.530380
last_received_ts = 74.529678
last_seq = 797
current_ts = 74.549370
current_received_ts = 74.548668
current_seq = 798
count = 777
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.530380/797, current 74.549370/798: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.549370
last_received_ts = 74.548668
last_seq = 798
current_ts = 74.568352
current_received_ts = 74.567650
current_seq = 799
count = 778
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.549370/798, current 74.568352/799: elapsed=18988.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.568352
last_received_ts = 74.567650
last_seq = 799
current_ts = 74.587334
current_received_ts = 74.586632
current_seq = 800
count = 779
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.568352/799, current 74.587334/800: elapsed=18979.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.587334
last_received_ts = 74.586632
last_seq = 800
current_ts = 74.606316
current_received_ts = 74.605614
current_seq = 801
count = 780
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.587334/800, current 74.606316/801: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.606316
last_received_ts = 74.605614
last_seq = 801
current_ts = 74.625298
current_received_ts = 74.624596
current_seq = 802
count = 781
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.606316/801, current 74.625298/802: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.625298
last_received_ts = 74.624596
last_seq = 802
current_ts = 74.644287
current_received_ts = 74.643585
current_seq = 803
count = 782
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.625298/802, current 74.644287/803: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.644287
last_received_ts = 74.643585
last_seq = 803
current_ts = 74.663269
current_received_ts = 74.662567
current_seq = 804
count = 783
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.644287/803, current 74.663269/804: elapsed=18983.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.663269
last_received_ts = 74.662567
last_seq = 804
current_ts = 74.682251
current_received_ts = 74.681549
current_seq = 805
count = 784
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.663269/804, current 74.682251/805: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.682251
last_received_ts = 74.681549
last_seq = 805
current_ts = 74.701241
current_received_ts = 74.700539
current_seq = 806
count = 785
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.682251/805, current 74.701241/806: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.701241
last_received_ts = 74.700539
last_seq = 806
current_ts = 74.720222
current_received_ts = 74.719521
current_seq = 807
count = 786
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.701241/806, current 74.720222/807: elapsed=18986.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.720222
last_received_ts = 74.719521
last_seq = 807
current_ts = 74.739204
current_received_ts = 74.738503
current_seq = 808
count = 787
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.720222/807, current 74.739204/808: elapsed=18982.0us expected=19050.0us +- 95.3us, error 0.4%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.739204
last_received_ts = 74.738503
last_seq = 808
current_ts = 74.758186
current_received_ts = 74.757484
current_seq = 809
count = 788
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.739204/808, current 74.758186/809: elapsed=18984.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: name = flip
last_ts = 74.758186
last_received_ts = 74.757484
last_seq = 809
current_ts = 74.777176
current_received_ts = 74.776474
current_seq = 810
count = 789
seq_step = 1
(kms_flip:1258) DEBUG: flip ts/seq: last 74.758186/809, current 74.777176/810: elapsed=18985.0us expected=19050.0us +- 95.3us, error 0.3%
(kms_flip:1258) DEBUG: expected 788, counted 790, encoder type 2
(kms_flip:1258) DEBUG: dropped frames, expected 788, counted 790, encoder type 2
(kms_flip:1258) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1258) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1258) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1258) igt_core-INFO: Stack trace:
(kms_flip:1258) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1258) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1258) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1258) igt_core-INFO:   #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
(kms_flip:1258) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1258) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1258) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1258) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (18.489s)
Subtest plain-flip-ts-check-interruptible: FAIL (25.415s)
(kms_flip:1258) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2234:
(kms_flip:1258) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:1258) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:1258) igt_kms-CRITICAL: DPMS property not found on 137
Dmesg
<6> [59.241137] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1
<7> [59.241900] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:166]
<7> [59.241984] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167]
<7> [59.259037] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [59.259092] 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> [59.259255] 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> [59.259409] 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> [59.259538] 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> [59.259689] 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> [59.259822] 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> [59.259982] 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> [59.260112] 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> [59.260237] 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> [59.260363] 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> [59.260486] 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> [59.260638] 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> [59.260783] 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> [59.260910] 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> [59.261036] 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> [59.261166] 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> [59.261299] 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> [59.261449] 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> [59.261575] 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> [59.261713] 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> [59.261836] 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> [59.261971] 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> [59.262116] 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> [59.262239] 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> [59.262362] 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> [59.262485] 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> [59.262632] 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> [59.262788] 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> [59.262915] 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> [59.263042] 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> [59.263167] 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> [59.263291] 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> [59.263448] 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> [59.263572] 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> [59.263711] 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> [59.263839] 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> [59.263970] 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> [59.264145] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [59.264276] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [59.264416] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [59.264540] 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> [59.264699] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [59.264818] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [59.264969] 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> [59.265089] 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> [59.265204] 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> [59.265320] 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> [59.265436] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [59.265568] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [59.265755] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [59.265886] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [59.266018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [59.266148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [59.266297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [59.266435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [59.266562] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [59.266709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [59.266836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [59.267028] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [59.267159] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [59.275705] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [59.296549] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [59.296972] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [59.302213] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [59.302619] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [59.302922] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [59.303280] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [59.303606] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [59.303911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [59.304220] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [59.304514] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [59.304850] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [59.305179] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [59.305501] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [59.306094] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [59.306271] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [59.306299] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:137:HDMI-A-1]
<7> [59.306380] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:137:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [59.306702] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [59.306913] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [59.307156] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:136:DDI B/PHY B] [CRTC:134:pipe B]
<7> [59.307419] 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> [59.307681] 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> [59.307911] 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> [59.308130] 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> [59.308307] 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> [59.308500] 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> [59.308680] 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> [59.308893] 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> [59.309065] 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> [59.309235] 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> [59.309409] 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> [59.309641] 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> [59.309814] 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> [59.309988] 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> [59.310176] 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> [59.310431] 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> [59.310603] 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> [59.310773] 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> [59.310958] 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> [59.311115] 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> [59.311240] 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> [59.311370] 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> [59.311514] 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> [59.311642] 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> [59.311796] 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> [59.311920] 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> [59.312046] 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> [59.312174] 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> [59.312301] 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> [59.312475] 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> [59.312600] 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> [59.312724] 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> [59.312848] 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> [59.312976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state
<7> [59.313126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.313248] 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> [59.313372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.313508] 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> [59.313641] 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> [59.313795] 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> [59.313920] 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> [59.314043] 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> [59.314165] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [59.314304] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [59.314468] 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> [59.314613] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [59.314732] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [59.314850] 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> [59.314973] 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> [59.315118] 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> [59.315238] 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> [59.315355] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [59.315496] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [59.315622] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [59.315794] 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> [59.315921] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [59.316049] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [59.316175] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [59.316303] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [59.316476] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [59.316606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [59.316736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [59.316864] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [59.316997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [59.317146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [59.317272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [59.317408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [59.317536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [59.317668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [59.317817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [59.317941] 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> [59.318067] 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> [59.318193] 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> [59.318320] 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> [59.318485] 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> [59.318612] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [59.318739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [59.318864] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [59.318994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [59.319148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [59.319272] 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> [59.319409] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [59.319532] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [59.319660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.319810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.319933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.320054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.320176] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.320300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.320472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.320599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.320727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.320853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.320977] 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> [59.321141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [59.321266] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [59.321400] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [59.321529] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [59.321652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [59.321812] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [59.321937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [59.322540] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [59.322744] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [59.322869] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [59.322990] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [59.323145] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [59.323265] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [59.323428] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [59.323587] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [59.323727] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [59.323959] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [59.329122] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [59.329312] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [59.349191] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [59.349512] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [77.732491] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL
<6> [77.735231] [IGT] kms_flip: finished subtest plain-flip-ts-check-interruptible, FAIL
<7> [77.741974] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:167] from all active usage due to RMFB ioctl
<7> [77.742231] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:167] is removed
<7> [77.742330] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [77.742482] 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> [77.742606] 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> [77.742727] 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> [77.742844] 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> [77.742961] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [77.743089] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [77.757044] [IGT] kms_flip: exiting, ret=98
<7> [77.769913] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:137:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [77.770068] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [77.770200] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [77.770342] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:136:DDI B/PHY B] [CRTC:82:pipe A]
<7> [77.770465] 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> [77.770598] 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> [77.770728] 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> [77.770857] 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> [77.770982] 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> [77.771108] 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> [77.771252] 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> [77.771378] 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> [77.771503] 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> [77.771627] 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> [77.771752] 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> [77.771877] 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> [77.772004] 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> [77.772139] 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> [77.772266] 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> [77.772392] 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> [77.772517] 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> [77.772641] 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> [77.772764] 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> [77.772888] 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> [77.773011] 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> [77.773139] 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> [77.773262] 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> [77.773387] 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> [77.773510] 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> [77.773633] 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> [77.773755] 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> [77.773879] 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> [77.774000] 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> [77.774123] 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> [77.774258] 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> [77.774382] 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> [77.774504] 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> [77.774627] 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> [77.774749] 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> [77.774871] 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> [77.774992] 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> [77.775114] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [77.775250] 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> [77.775372] 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> [77.775494] 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> [77.775617] 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> [77.775745] 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> [77.775874] 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> [77.776002] 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> [77.776134] 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> [77.776258] 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> [77.776382] 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> [77.776503] 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> [77.776628] 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> [77.776751] 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> [77.776874] 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> [77.776997] 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> [77.777120] 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> [77.777262] 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> [77.777385] 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> [77.777507] 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> [77.777630] 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> [77.777753] 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> [77.777873] 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> [77.777993] 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> [77.778115] 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> [77.778249] 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> [77.778373] 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> [77.778496] 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> [77.778617] 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> [77.778739] 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> [77.778862] 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> [77.778983] 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> [77.779106] 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> [77.779239] 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> [77.779362] 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> [77.779483] 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> [77.779607] 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> [77.779730] 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> [77.779853] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [77.779976] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [77.780120] 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> [77.780252] 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> [77.780384] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [77.780505] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [77.780624] 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> [77.780741] 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> [77.780858] 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> [77.780976] 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> [77.781091] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [77.781217] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [77.781346] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [77.781475] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [77.781613] 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> [77.781746] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [77.781872] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [77.782000] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [77.782133] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [77.782261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [77.782387] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [77.782513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [77.782642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [77.782771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [77.782898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [77.783025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [77.783157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [77.783279] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [77.783403] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [77.783529] 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> [77.783654] 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> [77.783779] 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> [77.783902] 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> [77.784027] 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> [77.784160] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [77.784283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [77.784407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [77.784531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [77.784653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [77.784777] 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> [77.784901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [77.785023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [77.785298] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.785426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.785554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.785679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.785806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.785932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.786058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.786193] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.786318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.786444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.786568] 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> [77.786693] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 1
<7> [77.786819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [77.786942] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [77.787064] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [77.787201] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [77.787330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [77.787455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [77.787578] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [77.787703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [77.787825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [77.787950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [77.788074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [77.788205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [77.788326] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [77.788847] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [77.793155] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [77.793309] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [77.798036] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [77.798215] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [77.798353] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [77.798477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [77.798600] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [77.798723] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [77.798845] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [77.799031] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [77.799173] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [77.799344] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [77.804882] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [77.805055] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [77.805419] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [77.805550] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [77.824506] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [77.824732] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [77.825363] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [77.825700] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [77.826252] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7> [77.844175] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [77.844729] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [77.845296] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
Created at 2024-11-13 14:11:52