Results for igt@kms_flip@flip-vs-expired-vblank-interruptible

Result: Fail

integration-manifest git-log-oneline i915_display_info22 igt_runner22 runtimes22 results22.json results22-i915-load.json guc_logs22.tar i915_display_info_post_exec22 boot22 dmesg22

DetailValue
Duration 15.79 seconds
Hostname
shard-rkl-4
Igt-Version
IGT-Version: 2.4-gaf4230819 (x86_64) (Linux: 7.1.0-rc4-CI_DRM_18513-g94e4b8dc66d1+ x86_64)
Out
Using IGT_SRANDOM=1779200073 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A2: FAIL (4.073s)
Starting dynamic subtest: B-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Dynamic subtest B-HDMI-A2: SUCCESS (10.171s)
Starting dynamic subtest: C-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-HDMI-A2: FAIL (1.539s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (15.789s)
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2960 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2415 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
[65.984389] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[65.984450] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500
[65.984493] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
[63.687841] (kms_flip:2123) DEBUG: Vblank took 33us
[63.706696] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.473347
last_received_ts = 63.472786
last_seq = 546
current_ts = 63.492233
current_received_ts = 63.491646
current_seq = 547
count = 79
seq_step = 1
[63.706746] (kms_flip:2123) DEBUG: Vblank took 36us
[63.725670] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.492233
last_received_ts = 63.491646
last_seq = 547
current_ts = 63.511131
current_received_ts = 63.510536
current_seq = 548
count = 80
seq_step = 1
[63.725720] (kms_flip:2123) DEBUG: Vblank took 35us
[63.744603] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.511131
last_received_ts = 63.510536
last_seq = 548
current_ts = 63.530025
current_received_ts = 63.529400
current_seq = 549
count = 81
seq_step = 1
[63.744642] (kms_flip:2123) DEBUG: Vblank took 29us
[63.763668] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.530025
last_received_ts = 63.529400
last_seq = 549
current_ts = 63.548920
current_received_ts = 63.548378
current_seq = 550
count = 82
seq_step = 1
[63.763715] (kms_flip:2123) DEBUG: Vblank took 33us
[63.782624] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.548920
last_received_ts = 63.548378
last_seq = 550
current_ts = 63.567806
current_received_ts = 63.567253
current_seq = 551
count = 83
seq_step = 1
[63.782671] (kms_flip:2123) DEBUG: Vblank took 33us
[63.801611] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.567806
last_received_ts = 63.567253
last_seq = 551
current_ts = 63.586693
current_received_ts = 63.586159
current_seq = 552
count = 84
seq_step = 1
[63.801658] (kms_flip:2123) DEBUG: Vblank took 33us
[63.820559] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.586693
last_received_ts = 63.586159
last_seq = 552
current_ts = 63.605598
current_received_ts = 63.605026
current_seq = 553
count = 85
seq_step = 1
[63.820606] (kms_flip:2123) DEBUG: Vblank took 33us
[63.839449] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.605598
last_received_ts = 63.605026
last_seq = 553
current_ts = 63.624481
current_received_ts = 63.623844
current_seq = 554
count = 86
seq_step = 1
[63.839487] (kms_flip:2123) DEBUG: Vblank took 29us
[63.858501] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.624481
last_received_ts = 63.623844
last_seq = 554
current_ts = 63.643387
current_received_ts = 63.642803
current_seq = 555
count = 87
seq_step = 1
[63.858550] (kms_flip:2123) DEBUG: Vblank took 35us
[63.877459] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.643387
last_received_ts = 63.642803
last_seq = 555
current_ts = 63.662262
current_received_ts = 63.661682
current_seq = 556
count = 88
seq_step = 1
[63.877506] (kms_flip:2123) DEBUG: Vblank took 33us
[63.896417] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.662262
last_received_ts = 63.661682
last_seq = 556
current_ts = 63.681168
current_received_ts = 63.680565
current_seq = 557
count = 89
seq_step = 1
[63.896458] (kms_flip:2123) DEBUG: Vblank took 30us
[63.915376] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.681168
last_received_ts = 63.680565
last_seq = 557
current_ts = 63.700066
current_received_ts = 63.699444
current_seq = 558
count = 90
seq_step = 1
[63.915419] (kms_flip:2123) DEBUG: Vblank took 31us
[63.934365] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.700066
last_received_ts = 63.699444
last_seq = 558
current_ts = 63.718956
current_received_ts = 63.718349
current_seq = 559
count = 91
seq_step = 1
[63.934412] (kms_flip:2123) DEBUG: Vblank took 32us
[63.953376] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.718956
last_received_ts = 63.718349
last_seq = 559
current_ts = 63.737846
current_received_ts = 63.737278
current_seq = 560
count = 92
seq_step = 1
[63.953423] (kms_flip:2123) DEBUG: Vblank took 33us
[63.972381] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.737846
last_received_ts = 63.737278
last_seq = 560
current_ts = 63.756729
current_received_ts = 63.756203
current_seq = 561
count = 93
seq_step = 1
[63.972429] (kms_flip:2123) DEBUG: Vblank took 33us
[63.991349] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.756729
last_received_ts = 63.756203
last_seq = 561
current_ts = 63.775623
current_received_ts = 63.775085
current_seq = 562
count = 94
seq_step = 1
[63.991399] (kms_flip:2123) DEBUG: Vblank took 36us
[64.010350] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.775623
last_received_ts = 63.775085
last_seq = 562
current_ts = 63.794521
current_received_ts = 63.793983
current_seq = 563
count = 95
seq_step = 1
[64.010402] (kms_flip:2123) DEBUG: Vblank took 37us
[64.029216] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.794521
last_received_ts = 63.793983
last_seq = 563
current_ts = 63.813419
current_received_ts = 63.812794
current_seq = 564
count = 96
seq_step = 1
[64.029267] (kms_flip:2123) DEBUG: Vblank took 36us
[64.048223] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.813419
last_received_ts = 63.812794
last_seq = 564
current_ts = 63.832310
current_received_ts = 63.831718
current_seq = 565
count = 97
seq_step = 1
[64.048274] (kms_flip:2123) DEBUG: Vblank took 36us
[64.067252] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.832310
last_received_ts = 63.831718
last_seq = 565
current_ts = 63.851204
current_received_ts = 63.850677
current_seq = 566
count = 98
seq_step = 1
[64.067291] (kms_flip:2123) DEBUG: Vblank took 29us
[64.086106] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.851204
last_received_ts = 63.850677
last_seq = 566
current_ts = 63.870094
current_received_ts = 63.869453
current_seq = 567
count = 99
seq_step = 1
[64.086145] (kms_flip:2123) DEBUG: Vblank took 29us
[64.105190] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.870094
last_received_ts = 63.869453
last_seq = 567
current_ts = 63.888988
current_received_ts = 63.888447
current_seq = 568
count = 100
seq_step = 1
[64.105240] (kms_flip:2123) DEBUG: Vblank took 35us
[64.124179] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.888988
last_received_ts = 63.888447
last_seq = 568
current_ts = 63.907871
current_received_ts = 63.907352
current_seq = 569
count = 101
seq_step = 1
[64.124230] (kms_flip:2123) DEBUG: Vblank took 36us
[64.143090] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.907871
last_received_ts = 63.907352
last_seq = 569
current_ts = 63.926769
current_received_ts = 63.926186
current_seq = 570
count = 102
seq_step = 1
[64.143140] (kms_flip:2123) DEBUG: Vblank took 35us
[64.162028] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.926769
last_received_ts = 63.926186
last_seq = 570
current_ts = 63.945663
current_received_ts = 63.945049
current_seq = 571
count = 103
seq_step = 1
[64.162076] (kms_flip:2123) DEBUG: Vblank took 38us
[64.181175] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.945663
last_received_ts = 63.945049
last_seq = 571
current_ts = 63.964554
current_received_ts = 63.964108
current_seq = 572
count = 104
seq_step = 1
[64.181226] (kms_flip:2123) DEBUG: Vblank took 37us
[64.200016] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.964554
last_received_ts = 63.964108
last_seq = 572
current_ts = 63.983459
current_received_ts = 63.982868
current_seq = 573
count = 105
seq_step = 1
[64.200065] (kms_flip:2123) DEBUG: Vblank took 36us
[64.218984] (kms_flip:2123) DEBUG: name = flip
last_ts = 63.983459
last_received_ts = 63.982868
last_seq = 573
current_ts = 64.002350
current_received_ts = 64.001755
current_seq = 574
count = 106
seq_step = 1
[64.219030] (kms_flip:2123) DEBUG: Vblank took 34us
[64.238004] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.002350
last_received_ts = 64.001755
last_seq = 574
current_ts = 64.021248
current_received_ts = 64.020691
current_seq = 575
count = 107
seq_step = 1
[64.238058] (kms_flip:2123) DEBUG: Vblank took 38us
[64.256920] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.021248
last_received_ts = 64.020691
last_seq = 575
current_ts = 64.040131
current_received_ts = 64.039536
current_seq = 576
count = 108
seq_step = 1
[64.256966] (kms_flip:2123) DEBUG: Vblank took 32us
[64.275899] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.040131
last_received_ts = 64.039536
last_seq = 576
current_ts = 64.059036
current_received_ts = 64.058426
current_seq = 577
count = 109
seq_step = 1
[64.275949] (kms_flip:2123) DEBUG: Vblank took 36us
[64.294836] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.059036
last_received_ts = 64.058426
last_seq = 577
current_ts = 64.077927
current_received_ts = 64.077286
current_seq = 578
count = 110
seq_step = 1
[64.294887] (kms_flip:2123) DEBUG: Vblank took 36us
[64.313897] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.077927
last_received_ts = 64.077286
last_seq = 578
current_ts = 64.096802
current_received_ts = 64.096268
current_seq = 579
count = 111
seq_step = 1
[64.313948] (kms_flip:2123) DEBUG: Vblank took 35us
[64.332881] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.096802
last_received_ts = 64.096268
last_seq = 579
current_ts = 64.115700
current_received_ts = 64.115166
current_seq = 580
count = 112
seq_step = 1
[64.332932] (kms_flip:2123) DEBUG: Vblank took 36us
[64.351843] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.115700
last_received_ts = 64.115166
last_seq = 580
current_ts = 64.134598
current_received_ts = 64.134048
current_seq = 581
count = 113
seq_step = 1
[64.351893] (kms_flip:2123) DEBUG: Vblank took 35us
[64.370740] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.134598
last_received_ts = 64.134048
last_seq = 581
current_ts = 64.153503
current_received_ts = 64.152878
current_seq = 582
count = 114
seq_step = 1
[64.370786] (kms_flip:2123) DEBUG: Vblank took 33us
[64.389794] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.153503
last_received_ts = 64.152878
last_seq = 582
current_ts = 64.172401
current_received_ts = 64.171852
current_seq = 583
count = 115
seq_step = 1
[64.389847] (kms_flip:2123) DEBUG: Vblank took 37us
[64.408726] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.172401
last_received_ts = 64.171852
last_seq = 583
current_ts = 64.191307
current_received_ts = 64.190712
current_seq = 584
count = 116
seq_step = 1
[64.408777] (kms_flip:2123) DEBUG: Vblank took 36us
[64.427690] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.191307
last_received_ts = 64.190712
last_seq = 584
current_ts = 64.210205
current_received_ts = 64.209610
current_seq = 585
count = 117
seq_step = 1
[64.427738] (kms_flip:2123) DEBUG: Vblank took 35us
[64.446633] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.210205
last_received_ts = 64.209610
last_seq = 585
current_ts = 64.229118
current_received_ts = 64.228485
current_seq = 586
count = 118
seq_step = 1
[64.446679] (kms_flip:2123) DEBUG: Vblank took 33us
[64.465758] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.229118
last_received_ts = 64.228485
last_seq = 586
current_ts = 64.248016
current_received_ts = 64.247536
current_seq = 587
count = 119
seq_step = 1
[64.465805] (kms_flip:2123) DEBUG: Vblank took 34us
[64.484707] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.248016
last_received_ts = 64.247536
last_seq = 587
current_ts = 64.266914
current_received_ts = 64.266418
current_seq = 588
count = 120
seq_step = 1
[64.484753] (kms_flip:2123) DEBUG: Vblank took 33us
[64.503525] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.266914
last_received_ts = 64.266418
last_seq = 588
current_ts = 64.285812
current_received_ts = 64.285164
current_seq = 589
count = 121
seq_step = 1
[64.503583] (kms_flip:2123) DEBUG: Vblank took 42us
[64.522529] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.285812
last_received_ts = 64.285164
last_seq = 589
current_ts = 64.304726
current_received_ts = 64.304108
current_seq = 590
count = 122
seq_step = 1
[64.522567] (kms_flip:2123) DEBUG: Vblank took 29us
[64.541548] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.304726
last_received_ts = 64.304108
last_seq = 590
current_ts = 64.323624
current_received_ts = 64.323051
current_seq = 591
count = 123
seq_step = 1
[64.541603] (kms_flip:2123) DEBUG: Vblank took 42us
[64.560485] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.323624
last_received_ts = 64.323051
last_seq = 591
current_ts = 64.342529
current_received_ts = 64.341919
current_seq = 592
count = 124
seq_step = 1
[64.560523] (kms_flip:2123) DEBUG: Vblank took 28us
[64.579481] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.342529
last_received_ts = 64.341919
last_seq = 592
current_ts = 64.361443
current_received_ts = 64.360840
current_seq = 593
count = 125
seq_step = 1
[64.579528] (kms_flip:2123) DEBUG: Vblank took 32us
[64.598529] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.361443
last_received_ts = 64.360840
last_seq = 593
current_ts = 64.380341
current_received_ts = 64.379814
current_seq = 594
count = 126
seq_step = 1
[64.598582] (kms_flip:2123) DEBUG: Vblank took 38us
[64.617471] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.380341
last_received_ts = 64.379814
last_seq = 594
current_ts = 64.399246
current_received_ts = 64.398682
current_seq = 595
count = 127
seq_step = 1
[64.617520] (kms_flip:2123) DEBUG: Vblank took 34us
[64.636387] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.399246
last_received_ts = 64.398682
last_seq = 595
current_ts = 64.418144
current_received_ts = 64.417534
current_seq = 596
count = 128
seq_step = 1
[64.636429] (kms_flip:2123) DEBUG: Vblank took 30us
[64.655492] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.418144
last_received_ts = 64.417534
last_seq = 596
current_ts = 64.437050
current_received_ts = 64.436569
current_seq = 597
count = 129
seq_step = 1
[64.655539] (kms_flip:2123) DEBUG: Vblank took 34us
[64.674374] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.437050
last_received_ts = 64.436569
last_seq = 597
current_ts = 64.455940
current_received_ts = 64.455376
current_seq = 598
count = 130
seq_step = 1
[64.674427] (kms_flip:2123) DEBUG: Vblank took 37us
[64.693320] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.455940
last_received_ts = 64.455376
last_seq = 598
current_ts = 64.474854
current_received_ts = 64.474251
current_seq = 599
count = 131
seq_step = 1
[64.693370] (kms_flip:2123) DEBUG: Vblank took 36us
[64.712362] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.474854
last_received_ts = 64.474251
last_seq = 599
current_ts = 64.493759
current_received_ts = 64.493225
current_seq = 600
count = 132
seq_step = 1
[64.712414] (kms_flip:2123) DEBUG: Vblank took 36us
[64.731311] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.493759
last_received_ts = 64.493225
last_seq = 600
current_ts = 64.512665
current_received_ts = 64.512100
current_seq = 601
count = 133
seq_step = 1
[64.731362] (kms_flip:2123) DEBUG: Vblank took 36us
[64.750216] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.512665
last_received_ts = 64.512100
last_seq = 601
current_ts = 64.531563
current_received_ts = 64.530937
current_seq = 602
count = 134
seq_step = 1
[64.750295] (kms_flip:2123) DEBUG: Vblank took 65us
[64.769283] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.531563
last_received_ts = 64.530937
last_seq = 602
current_ts = 64.550468
current_received_ts = 64.549934
current_seq = 603
count = 135
seq_step = 1
[64.769362] (kms_flip:2123) DEBUG: Vblank took 64us
[64.788195] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.550468
last_received_ts = 64.549934
last_seq = 603
current_ts = 64.569366
current_received_ts = 64.568771
current_seq = 604
count = 136
seq_step = 1
[64.788246] (kms_flip:2123) DEBUG: Vblank took 36us
[64.807173] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.569366
last_received_ts = 64.568771
last_seq = 604
current_ts = 64.588272
current_received_ts = 64.587685
current_seq = 605
count = 137
seq_step = 1
[64.807224] (kms_flip:2123) DEBUG: Vblank took 36us
[64.826129] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.588272
last_received_ts = 64.587685
last_seq = 605
current_ts = 64.607170
current_received_ts = 64.606567
current_seq = 606
count = 138
seq_step = 1
[64.826183] (kms_flip:2123) DEBUG: Vblank took 37us
[64.845130] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.607170
last_received_ts = 64.606567
last_seq = 606
current_ts = 64.626083
current_received_ts = 64.625504
current_seq = 607
count = 139
seq_step = 1
[64.845211] (kms_flip:2123) DEBUG: Vblank took 38us
[64.864127] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.626083
last_received_ts = 64.625504
last_seq = 607
current_ts = 64.644974
current_received_ts = 64.644424
current_seq = 608
count = 140
seq_step = 1
[64.864174] (kms_flip:2123) DEBUG: Vblank took 34us
[64.883053] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.644974
last_received_ts = 64.644424
last_seq = 608
current_ts = 64.663879
current_received_ts = 64.663277
current_seq = 609
count = 141
seq_step = 1
[64.883106] (kms_flip:2123) DEBUG: Vblank took 36us
[64.902029] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.663879
last_received_ts = 64.663277
last_seq = 609
current_ts = 64.682785
current_received_ts = 64.682182
current_seq = 610
count = 142
seq_step = 1
[64.902081] (kms_flip:2123) DEBUG: Vblank took 36us
[64.920978] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.682785
last_received_ts = 64.682182
last_seq = 610
current_ts = 64.701683
current_received_ts = 64.701073
current_seq = 611
count = 143
seq_step = 1
[64.921020] (kms_flip:2123) DEBUG: Vblank took 31us
[64.940025] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.701683
last_received_ts = 64.701073
last_seq = 611
current_ts = 64.720596
current_received_ts = 64.720032
current_seq = 612
count = 144
seq_step = 1
[64.940078] (kms_flip:2123) DEBUG: Vblank took 37us
[64.958968] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.720596
last_received_ts = 64.720032
last_seq = 612
current_ts = 64.739502
current_received_ts = 64.738907
current_seq = 613
count = 145
seq_step = 1
[64.959019] (kms_flip:2123) DEBUG: Vblank took 36us
[64.977939] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.739502
last_received_ts = 64.738907
last_seq = 613
current_ts = 64.758408
current_received_ts = 64.757812
current_seq = 614
count = 146
seq_step = 1
[64.977992] (kms_flip:2123) DEBUG: Vblank took 36us
[64.996887] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.758408
last_received_ts = 64.757812
last_seq = 614
current_ts = 64.777298
current_received_ts = 64.776688
current_seq = 615
count = 147
seq_step = 1
[64.996939] (kms_flip:2123) DEBUG: Vblank took 36us
[65.015933] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.777298
last_received_ts = 64.776688
last_seq = 615
current_ts = 64.796211
current_received_ts = 64.795662
current_seq = 616
count = 148
seq_step = 1
[65.015985] (kms_flip:2123) DEBUG: Vblank took 35us
[65.034848] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.796211
last_received_ts = 64.795662
last_seq = 616
current_ts = 64.815102
current_received_ts = 64.814514
current_seq = 617
count = 149
seq_step = 1
[65.034893] (kms_flip:2123) DEBUG: Vblank took 32us
[65.053833] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.815102
last_received_ts = 64.814514
last_seq = 617
current_ts = 64.834007
current_received_ts = 64.833420
current_seq = 618
count = 150
seq_step = 1
[65.053885] (kms_flip:2123) DEBUG: Vblank took 36us
[65.072851] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.834007
last_received_ts = 64.833420
last_seq = 618
current_ts = 64.852921
current_received_ts = 64.852371
current_seq = 619
count = 151
seq_step = 1
[65.072903] (kms_flip:2123) DEBUG: Vblank took 36us
[65.091809] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.852921
last_received_ts = 64.852371
last_seq = 619
current_ts = 64.871819
current_received_ts = 64.871254
current_seq = 620
count = 152
seq_step = 1
[65.091864] (kms_flip:2123) DEBUG: Vblank took 37us
[65.110746] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.871819
last_received_ts = 64.871254
last_seq = 620
current_ts = 64.890724
current_received_ts = 64.890121
current_seq = 621
count = 153
seq_step = 1
[65.110798] (kms_flip:2123) DEBUG: Vblank took 36us
[65.129752] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.890724
last_received_ts = 64.890121
last_seq = 621
current_ts = 64.909622
current_received_ts = 64.909058
current_seq = 622
count = 154
seq_step = 1
[65.129803] (kms_flip:2123) DEBUG: Vblank took 36us
[65.148774] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.909622
last_received_ts = 64.909058
last_seq = 622
current_ts = 64.928528
current_received_ts = 64.927979
current_seq = 623
count = 155
seq_step = 1
[65.148830] (kms_flip:2123) DEBUG: Vblank took 38us
[65.167679] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.928528
last_received_ts = 64.927979
last_seq = 623
current_ts = 64.947433
current_received_ts = 64.946846
current_seq = 624
count = 156
seq_step = 1
[65.167730] (kms_flip:2123) DEBUG: Vblank took 36us
[65.186832] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.947433
last_received_ts = 64.946846
last_seq = 624
current_ts = 64.966331
current_received_ts = 64.965927
current_seq = 625
count = 157
seq_step = 1
[65.186914] (kms_flip:2123) DEBUG: Vblank took 65us
[65.205597] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.966331
last_received_ts = 64.965927
last_seq = 625
current_ts = 64.985229
current_received_ts = 64.984627
current_seq = 626
count = 158
seq_step = 1
[65.205648] (kms_flip:2123) DEBUG: Vblank took 35us
[65.224648] (kms_flip:2123) DEBUG: name = flip
last_ts = 64.985229
last_received_ts = 64.984627
last_seq = 626
current_ts = 65.004150
current_received_ts = 65.003601
current_seq = 627
count = 159
seq_step = 1
[65.224728] (kms_flip:2123) DEBUG: Vblank took 64us
[65.243589] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.004150
last_received_ts = 65.003601
last_seq = 627
current_ts = 65.023041
current_received_ts = 65.022469
current_seq = 628
count = 160
seq_step = 1
[65.243641] (kms_flip:2123) DEBUG: Vblank took 36us
[65.262500] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.023041
last_received_ts = 65.022469
last_seq = 628
current_ts = 65.041946
current_received_ts = 65.041321
current_seq = 629
count = 161
seq_step = 1
[65.262543] (kms_flip:2123) DEBUG: Vblank took 32us
[65.281500] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.041946
last_received_ts = 65.041321
last_seq = 629
current_ts = 65.060844
current_received_ts = 65.060242
current_seq = 630
count = 162
seq_step = 1
[65.281552] (kms_flip:2123) DEBUG: Vblank took 36us
[65.300567] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.060844
last_received_ts = 65.060242
last_seq = 630
current_ts = 65.079750
current_received_ts = 65.079239
current_seq = 631
count = 163
seq_step = 1
[65.300620] (kms_flip:2123) DEBUG: Vblank took 36us
[65.319465] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.079750
last_received_ts = 65.079239
last_seq = 631
current_ts = 65.098656
current_received_ts = 65.098068
current_seq = 632
count = 164
seq_step = 1
[65.319518] (kms_flip:2123) DEBUG: Vblank took 37us
[65.338519] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.098656
last_received_ts = 65.098068
last_seq = 632
current_ts = 65.117561
current_received_ts = 65.117050
current_seq = 633
count = 165
seq_step = 1
[65.338571] (kms_flip:2123) DEBUG: Vblank took 36us
[65.357422] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.117561
last_received_ts = 65.117050
last_seq = 633
current_ts = 65.136452
current_received_ts = 65.135880
current_seq = 634
count = 166
seq_step = 1
[65.357476] (kms_flip:2123) DEBUG: Vblank took 37us
[65.376434] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.136452
last_received_ts = 65.135880
last_seq = 634
current_ts = 65.155373
current_received_ts = 65.154831
current_seq = 635
count = 167
seq_step = 1
[65.376486] (kms_flip:2123) DEBUG: Vblank took 36us
[65.395462] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.155373
last_received_ts = 65.154831
last_seq = 635
current_ts = 65.174278
current_received_ts = 65.173798
current_seq = 636
count = 168
seq_step = 1
[65.395510] (kms_flip:2123) DEBUG: Vblank took 34us
[65.414342] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.174278
last_received_ts = 65.173798
last_seq = 636
current_ts = 65.193192
current_received_ts = 65.192612
current_seq = 637
count = 169
seq_step = 1
[65.414394] (kms_flip:2123) DEBUG: Vblank took 36us
[65.433402] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.193192
last_received_ts = 65.192612
last_seq = 637
current_ts = 65.212120
current_received_ts = 65.211617
current_seq = 638
count = 170
seq_step = 1
[65.433453] (kms_flip:2123) DEBUG: Vblank took 36us
[65.452264] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.212120
last_received_ts = 65.211617
last_seq = 638
current_ts = 65.231018
current_received_ts = 65.230415
current_seq = 639
count = 171
seq_step = 1
[65.452317] (kms_flip:2123) DEBUG: Vblank took 37us
[65.471300] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.231018
last_received_ts = 65.230415
last_seq = 639
current_ts = 65.249931
current_received_ts = 65.249390
current_seq = 640
count = 172
seq_step = 1
[65.471352] (kms_flip:2123) DEBUG: Vblank took 37us
[65.490263] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.249931
last_received_ts = 65.249390
last_seq = 640
current_ts = 65.268845
current_received_ts = 65.268288
current_seq = 641
count = 173
seq_step = 1
[65.490317] (kms_flip:2123) DEBUG: Vblank took 37us
[65.509165] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.268845
last_received_ts = 65.268288
last_seq = 641
current_ts = 65.287758
current_received_ts = 65.287140
current_seq = 642
count = 174
seq_step = 1
[65.509206] (kms_flip:2123) DEBUG: Vblank took 30us
[65.528163] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.287758
last_received_ts = 65.287140
last_seq = 642
current_ts = 65.306664
current_received_ts = 65.306068
current_seq = 643
count = 175
seq_step = 1
[65.528211] (kms_flip:2123) DEBUG: Vblank took 33us
[65.547231] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.306664
last_received_ts = 65.306068
last_seq = 643
current_ts = 65.325584
current_received_ts = 65.325081
current_seq = 644
count = 176
seq_step = 1
[65.547271] (kms_flip:2123) DEBUG: Vblank took 29us
[65.566126] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.325584
last_received_ts = 65.325081
last_seq = 644
current_ts = 65.344498
current_received_ts = 65.343903
current_seq = 645
count = 177
seq_step = 1
[65.566177] (kms_flip:2123) DEBUG: Vblank took 36us
[65.585190] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.344498
last_received_ts = 65.343903
last_seq = 645
current_ts = 65.363411
current_received_ts = 65.362907
current_seq = 646
count = 178
seq_step = 1
[65.585243] (kms_flip:2123) DEBUG: Vblank took 36us
[65.604038] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.363411
last_received_ts = 65.362907
last_seq = 646
current_ts = 65.382317
current_received_ts = 65.381706
current_seq = 647
count = 179
seq_step = 1
[65.604077] (kms_flip:2123) DEBUG: Vblank took 29us
[65.622994] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.382317
last_received_ts = 65.381706
last_seq = 647
current_ts = 65.401222
current_received_ts = 65.400597
current_seq = 648
count = 180
seq_step = 1
[65.623033] (kms_flip:2123) DEBUG: Vblank took 29us
[65.642049] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.401222
last_received_ts = 65.400597
last_seq = 648
current_ts = 65.420151
current_received_ts = 65.419586
current_seq = 649
count = 181
seq_step = 1
[65.642092] (kms_flip:2123) DEBUG: Vblank took 31us
[65.661091] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.420151
last_received_ts = 65.419586
last_seq = 649
current_ts = 65.439056
current_received_ts = 65.438560
current_seq = 650
count = 182
seq_step = 1
[65.661144] (kms_flip:2123) DEBUG: Vblank took 37us
[65.679926] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.439056
last_received_ts = 65.438560
last_seq = 650
current_ts = 65.457962
current_received_ts = 65.457344
current_seq = 651
count = 183
seq_step = 1
[65.679969] (kms_flip:2123) DEBUG: Vblank took 32us
[65.698925] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.457962
last_received_ts = 65.457344
last_seq = 651
current_ts = 65.476875
current_received_ts = 65.476273
current_seq = 652
count = 184
seq_step = 1
[65.698977] (kms_flip:2123) DEBUG: Vblank took 36us
[65.717979] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.476875
last_received_ts = 65.476273
last_seq = 652
current_ts = 65.495796
current_received_ts = 65.495262
current_seq = 653
count = 185
seq_step = 1
[65.718063] (kms_flip:2123) DEBUG: Vblank took 68us
[65.736959] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.495796
last_received_ts = 65.495262
last_seq = 653
current_ts = 65.514702
current_received_ts = 65.514183
current_seq = 654
count = 186
seq_step = 1
[65.737006] (kms_flip:2123) DEBUG: Vblank took 35us
[65.755879] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.514702
last_received_ts = 65.514183
last_seq = 654
current_ts = 65.533615
current_received_ts = 65.533043
current_seq = 655
count = 187
seq_step = 1
[65.755930] (kms_flip:2123) DEBUG: Vblank took 36us
[65.774807] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.533615
last_received_ts = 65.533043
last_seq = 655
current_ts = 65.552521
current_received_ts = 65.551918
current_seq = 656
count = 188
seq_step = 1
[65.774850] (kms_flip:2123) DEBUG: Vblank took 31us
[65.793791] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.552521
last_received_ts = 65.551918
last_seq = 656
current_ts = 65.571426
current_received_ts = 65.570831
current_seq = 657
count = 189
seq_step = 1
[65.793844] (kms_flip:2123) DEBUG: Vblank took 36us
[65.812840] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.571426
last_received_ts = 65.570831
last_seq = 657
current_ts = 65.590347
current_received_ts = 65.589821
current_seq = 658
count = 190
seq_step = 1
[65.812889] (kms_flip:2123) DEBUG: Vblank took 34us
[65.831726] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.590347
last_received_ts = 65.589821
last_seq = 658
current_ts = 65.609261
current_received_ts = 65.608650
current_seq = 659
count = 191
seq_step = 1
[65.831765] (kms_flip:2123) DEBUG: Vblank took 29us
[65.850737] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.609261
last_received_ts = 65.608650
last_seq = 659
current_ts = 65.628181
current_received_ts = 65.627586
current_seq = 660
count = 192
seq_step = 1
[65.850789] (kms_flip:2123) DEBUG: Vblank took 36us
[65.869668] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.628181
last_received_ts = 65.627586
last_seq = 660
current_ts = 65.647087
current_received_ts = 65.646461
current_seq = 661
count = 193
seq_step = 1
[65.869716] (kms_flip:2123) DEBUG: Vblank took 35us
[65.888700] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.647087
last_received_ts = 65.646461
last_seq = 661
current_ts = 65.666000
current_received_ts = 65.665436
current_seq = 662
count = 194
seq_step = 1
[65.888744] (kms_flip:2123) DEBUG: Vblank took 32us
[65.907668] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.666000
last_received_ts = 65.665436
last_seq = 662
current_ts = 65.684914
current_received_ts = 65.684341
current_seq = 663
count = 195
seq_step = 1
[65.907712] (kms_flip:2123) DEBUG: Vblank took 32us
[65.926612] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.684914
last_received_ts = 65.684341
last_seq = 663
current_ts = 65.703819
current_received_ts = 65.703217
current_seq = 664
count = 196
seq_step = 1
[65.926663] (kms_flip:2123) DEBUG: Vblank took 36us
[65.945588] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.703819
last_received_ts = 65.703217
last_seq = 664
current_ts = 65.722740
current_received_ts = 65.722130
current_seq = 665
count = 197
seq_step = 1
[65.945641] (kms_flip:2123) DEBUG: Vblank took 36us
[65.965318] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.722740
last_received_ts = 65.722130
last_seq = 665
current_ts = 65.741653
current_received_ts = 65.741806
current_seq = 666
count = 198
seq_step = 1
[65.965360] (kms_flip:2123) DEBUG: Vblank took 31us
[65.983536] (kms_flip:2123) DEBUG: name = flip
last_ts = 65.741653
last_received_ts = 65.741806
last_seq = 666
current_ts = 65.760559
current_received_ts = 65.759956
current_seq = 667
count = 199
seq_step = 1
[65.984381] (kms_flip:2123) DEBUG: Vblank took 827us
[65.984389] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[65.984450] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500
[65.984493] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call
[66.023288] (kms_flip:2123) igt_core-INFO: Stack trace:
[66.040315] (kms_flip:2123) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[66.041516] (kms_flip:2123) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[66.041586] (kms_flip:2123) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[66.041636] (kms_flip:2123) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[66.041667] (kms_flip:2123) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[66.048324] (kms_flip:2123) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[66.049464] (kms_flip:2123) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[66.049686] (kms_flip:2123) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A2: FAIL (4.073s)
Starting dynamic subtest: B-HDMI-A2
Dynamic subtest B-HDMI-A2: SUCCESS (10.171s)
Starting dynamic subtest: C-HDMI-A2
[77.772600] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[77.772715] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500
[77.772765] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A2 failed.
**** DEBUG ****
[76.255059] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
[76.255076] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
[76.255093] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
[76.255203] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
[76.255207] (kms_flip:2123) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
[76.255268] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
[76.255281] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
[76.255298] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
[76.255389] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=7680)
[76.255391] (kms_flip:2123) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
[76.255448] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info
[76.255474] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info
[76.257125] (kms_flip:2123) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
[76.264611] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info
[76.264637] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info
[76.265518] (kms_flip:2123) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
[76.272574] (kms_flip:2123) igt_kms-INFO:   1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
[76.272619] (kms_flip:2123) DEBUG: No stale events found
[76.351934] (kms_flip:2123) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
[76.352202] (kms_flip:2123) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
[76.386510] (kms_flip:2123) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 76.145508
current_received_ts = 76.144859
current_seq = 5
count = 0
seq_step = 1
[76.386563] (kms_flip:2123) DEBUG: Vblank took 36us
[76.405507] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.145508
last_received_ts = 76.144859
last_seq = 5
current_ts = 76.164467
current_received_ts = 76.163841
current_seq = 6
count = 1
seq_step = 1
[76.405554] (kms_flip:2123) DEBUG: Vblank took 33us
[76.424475] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.164467
last_received_ts = 76.163841
last_seq = 6
current_ts = 76.183426
current_received_ts = 76.182793
current_seq = 7
count = 2
seq_step = 1
[76.424524] (kms_flip:2123) DEBUG: Vblank took 34us
[76.443458] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.183426
last_received_ts = 76.182793
last_seq = 7
current_ts = 76.202385
current_received_ts = 76.201767
current_seq = 8
count = 3
seq_step = 1
[76.443506] (kms_flip:2123) DEBUG: Vblank took 34us
[76.462481] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.202385
last_received_ts = 76.201767
last_seq = 8
current_ts = 76.221344
current_received_ts = 76.220772
current_seq = 9
count = 4
seq_step = 1
[76.462529] (kms_flip:2123) DEBUG: Vblank took 34us
[76.481394] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.221344
last_received_ts = 76.220772
last_seq = 9
current_ts = 76.240303
current_received_ts = 76.239670
current_seq = 10
count = 5
seq_step = 1
[76.481443] (kms_flip:2123) DEBUG: Vblank took 34us
[76.500350] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.240303
last_received_ts = 76.239670
last_seq = 10
current_ts = 76.259262
current_received_ts = 76.258621
current_seq = 11
count = 6
seq_step = 1
[76.500391] (kms_flip:2123) DEBUG: Vblank took 30us
[76.519346] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.259262
last_received_ts = 76.258621
last_seq = 11
current_ts = 76.278221
current_received_ts = 76.277596
current_seq = 12
count = 7
seq_step = 1
[76.519396] (kms_flip:2123) DEBUG: Vblank took 35us
[76.538334] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.278221
last_received_ts = 76.277596
last_seq = 12
current_ts = 76.297180
current_received_ts = 76.296570
current_seq = 13
count = 8
seq_step = 1
[76.538385] (kms_flip:2123) DEBUG: Vblank took 34us
[76.557355] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.297180
last_received_ts = 76.296570
last_seq = 13
current_ts = 76.316147
current_received_ts = 76.315575
current_seq = 14
count = 9
seq_step = 1
[76.557403] (kms_flip:2123) DEBUG: Vblank took 34us
[76.576279] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.316147
last_received_ts = 76.315575
last_seq = 14
current_ts = 76.335091
current_received_ts = 76.334488
current_seq = 15
count = 10
seq_step = 1
[76.576327] (kms_flip:2123) DEBUG: Vblank took 34us
[76.595204] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.335091
last_received_ts = 76.334488
last_seq = 15
current_ts = 76.354057
current_received_ts = 76.353401
current_seq = 16
count = 11
seq_step = 1
[76.595245] (kms_flip:2123) DEBUG: Vblank took 30us
[76.614303] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.354057
last_received_ts = 76.353401
last_seq = 16
current_ts = 76.373024
current_received_ts = 76.372490
current_seq = 17
count = 12
seq_step = 1
[76.614344] (kms_flip:2123) DEBUG: Vblank took 31us
[76.633217] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.373024
last_received_ts = 76.372490
last_seq = 17
current_ts = 76.391983
current_received_ts = 76.391388
current_seq = 18
count = 13
seq_step = 1
[76.633255] (kms_flip:2123) DEBUG: Vblank took 29us
[76.652198] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.391983
last_received_ts = 76.391388
last_seq = 18
current_ts = 76.410927
current_received_ts = 76.410347
current_seq = 19
count = 14
seq_step = 1
[76.652243] (kms_flip:2123) DEBUG: Vblank took 31us
[76.671124] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.410927
last_received_ts = 76.410347
last_seq = 19
current_ts = 76.429886
current_received_ts = 76.429260
current_seq = 20
count = 15
seq_step = 1
[76.671172] (kms_flip:2123) DEBUG: Vblank took 34us
[76.690102] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.429886
last_received_ts = 76.429260
last_seq = 20
current_ts = 76.448853
current_received_ts = 76.448219
current_seq = 21
count = 16
seq_step = 1
[76.690153] (kms_flip:2123) DEBUG: Vblank took 34us
[76.709078] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.448853
last_received_ts = 76.448219
last_seq = 21
current_ts = 76.467812
current_received_ts = 76.467186
current_seq = 22
count = 17
seq_step = 1
[76.709125] (kms_flip:2123) DEBUG: Vblank took 33us
[76.728070] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.467812
last_received_ts = 76.467186
last_seq = 22
current_ts = 76.486771
current_received_ts = 76.486160
current_seq = 23
count = 18
seq_step = 1
[76.728118] (kms_flip:2123) DEBUG: Vblank took 34us
[76.747044] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.486771
last_received_ts = 76.486160
last_seq = 23
current_ts = 76.505730
current_received_ts = 76.505119
current_seq = 24
count = 19
seq_step = 1
[76.747093] (kms_flip:2123) DEBUG: Vblank took 34us
[76.766131] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.505730
last_received_ts = 76.505119
last_seq = 24
current_ts = 76.524689
current_received_ts = 76.524193
current_seq = 25
count = 20
seq_step = 1
[76.766181] (kms_flip:2123) DEBUG: Vblank took 35us
[76.785005] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.524689
last_received_ts = 76.524193
last_seq = 25
current_ts = 76.543655
current_received_ts = 76.543053
current_seq = 26
count = 21
seq_step = 1
[76.785055] (kms_flip:2123) DEBUG: Vblank took 34us
[76.803977] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.543655
last_received_ts = 76.543053
last_seq = 26
current_ts = 76.562607
current_received_ts = 76.561981
current_seq = 27
count = 22
seq_step = 1
[76.804042] (kms_flip:2123) DEBUG: Vblank took 47us
[76.823023] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.562607
last_received_ts = 76.561981
last_seq = 27
current_ts = 76.581566
current_received_ts = 76.581039
current_seq = 28
count = 23
seq_step = 1
[76.823110] (kms_flip:2123) DEBUG: Vblank took 71us
[76.841906] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.581566
last_received_ts = 76.581039
last_seq = 28
current_ts = 76.600540
current_received_ts = 76.599915
current_seq = 29
count = 24
seq_step = 1
[76.841956] (kms_flip:2123) DEBUG: Vblank took 33us
[76.860879] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.600540
last_received_ts = 76.599915
last_seq = 29
current_ts = 76.619499
current_received_ts = 76.618874
current_seq = 30
count = 25
seq_step = 1
[76.860927] (kms_flip:2123) DEBUG: Vblank took 35us
[76.879821] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.619499
last_received_ts = 76.618874
last_seq = 30
current_ts = 76.638451
current_received_ts = 76.637810
current_seq = 31
count = 26
seq_step = 1
[76.879862] (kms_flip:2123) DEBUG: Vblank took 30us
[76.898790] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.638451
last_received_ts = 76.637810
last_seq = 31
current_ts = 76.657417
current_received_ts = 76.656761
current_seq = 32
count = 27
seq_step = 1
[76.898831] (kms_flip:2123) DEBUG: Vblank took 30us
[76.917853] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.657417
last_received_ts = 76.656761
last_seq = 32
current_ts = 76.676376
current_received_ts = 76.675804
current_seq = 33
count = 28
seq_step = 1
[76.917902] (kms_flip:2123) DEBUG: Vblank took 34us
[76.936772] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.676376
last_received_ts = 76.675804
last_seq = 33
current_ts = 76.695335
current_received_ts = 76.694710
current_seq = 34
count = 29
seq_step = 1
[76.936820] (kms_flip:2123) DEBUG: Vblank took 34us
[76.955801] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.695335
last_received_ts = 76.694710
last_seq = 34
current_ts = 76.714294
current_received_ts = 76.713722
current_seq = 35
count = 30
seq_step = 1
[76.955850] (kms_flip:2123) DEBUG: Vblank took 36us
[76.974704] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.714294
last_received_ts = 76.713722
last_seq = 35
current_ts = 76.733238
current_received_ts = 76.732613
current_seq = 36
count = 31
seq_step = 1
[76.974754] (kms_flip:2123) DEBUG: Vblank took 34us
[76.993778] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.733238
last_received_ts = 76.732613
last_seq = 36
current_ts = 76.752213
current_received_ts = 76.751671
current_seq = 37
count = 32
seq_step = 1
[76.993829] (kms_flip:2123) DEBUG: Vblank took 34us
[77.012720] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.752213
last_received_ts = 76.751671
last_seq = 37
current_ts = 76.771172
current_received_ts = 76.770599
current_seq = 38
count = 33
seq_step = 1
[77.012768] (kms_flip:2123) DEBUG: Vblank took 34us
[77.031642] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.771172
last_received_ts = 76.770599
last_seq = 38
current_ts = 76.790131
current_received_ts = 76.789505
current_seq = 39
count = 34
seq_step = 1
[77.031691] (kms_flip:2123) DEBUG: Vblank took 34us
[77.050613] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.790131
last_received_ts = 76.789505
last_seq = 39
current_ts = 76.809090
current_received_ts = 76.808464
current_seq = 40
count = 35
seq_step = 1
[77.050662] (kms_flip:2123) DEBUG: Vblank took 33us
[77.069591] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.809090
last_received_ts = 76.808464
last_seq = 40
current_ts = 76.828049
current_received_ts = 76.827423
current_seq = 41
count = 36
seq_step = 1
[77.069641] (kms_flip:2123) DEBUG: Vblank took 34us
[77.088609] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.828049
last_received_ts = 76.827423
last_seq = 41
current_ts = 76.847000
current_received_ts = 76.846428
current_seq = 42
count = 37
seq_step = 1
[77.088659] (kms_flip:2123) DEBUG: Vblank took 35us
[77.107541] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.847000
last_received_ts = 76.846428
last_seq = 42
current_ts = 76.865959
current_received_ts = 76.865349
current_seq = 43
count = 38
seq_step = 1
[77.107589] (kms_flip:2123) DEBUG: Vblank took 34us
[77.126468] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.865959
last_received_ts = 76.865349
last_seq = 43
current_ts = 76.884926
current_received_ts = 76.884270
current_seq = 44
count = 39
seq_step = 1
[77.126509] (kms_flip:2123) DEBUG: Vblank took 31us
[77.145471] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.884926
last_received_ts = 76.884270
last_seq = 44
current_ts = 76.903877
current_received_ts = 76.903252
current_seq = 45
count = 40
seq_step = 1
[77.145522] (kms_flip:2123) DEBUG: Vblank took 34us
[77.164492] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.903877
last_received_ts = 76.903252
last_seq = 45
current_ts = 76.922852
current_received_ts = 76.922256
current_seq = 46
count = 41
seq_step = 1
[77.164541] (kms_flip:2123) DEBUG: Vblank took 34us
[77.183459] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.922852
last_received_ts = 76.922256
last_seq = 46
current_ts = 76.941803
current_received_ts = 76.941216
current_seq = 47
count = 42
seq_step = 1
[77.183500] (kms_flip:2123) DEBUG: Vblank took 30us
[77.202517] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.941803
last_received_ts = 76.941216
last_seq = 47
current_ts = 76.960770
current_received_ts = 76.960251
current_seq = 48
count = 43
seq_step = 1
[77.202569] (kms_flip:2123) DEBUG: Vblank took 36us
[77.221392] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.960770
last_received_ts = 76.960251
last_seq = 48
current_ts = 76.979729
current_received_ts = 76.979111
current_seq = 49
count = 44
seq_step = 1
[77.221441] (kms_flip:2123) DEBUG: Vblank took 34us
[77.240383] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.979729
last_received_ts = 76.979111
last_seq = 49
current_ts = 76.998680
current_received_ts = 76.998093
current_seq = 50
count = 45
seq_step = 1
[77.240433] (kms_flip:2123) DEBUG: Vblank took 34us
[77.259509] (kms_flip:2123) DEBUG: name = flip
last_ts = 76.998680
last_received_ts = 76.998093
last_seq = 50
current_ts = 77.017639
current_received_ts = 77.017166
current_seq = 51
count = 46
seq_step = 1
[77.259563] (kms_flip:2123) DEBUG: Vblank took 36us
[77.278333] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.017639
last_received_ts = 77.017166
last_seq = 51
current_ts = 77.036606
current_received_ts = 77.035980
current_seq = 52
count = 47
seq_step = 1
[77.278400] (kms_flip:2123) DEBUG: Vblank took 47us
[77.297422] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.036606
last_received_ts = 77.035980
last_seq = 52
current_ts = 77.055550
current_received_ts = 77.055084
current_seq = 53
count = 48
seq_step = 1
[77.297473] (kms_flip:2123) DEBUG: Vblank took 34us
[77.316245] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.055550
last_received_ts = 77.055084
last_seq = 53
current_ts = 77.074524
current_received_ts = 77.073898
current_seq = 54
count = 49
seq_step = 1
[77.316295] (kms_flip:2123) DEBUG: Vblank took 33us
[77.335221] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.074524
last_received_ts = 77.073898
last_seq = 54
current_ts = 77.093483
current_received_ts = 77.092857
current_seq = 55
count = 50
seq_step = 1
[77.335273] (kms_flip:2123) DEBUG: Vblank took 34us
[77.354267] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.093483
last_received_ts = 77.092857
last_seq = 55
current_ts = 77.112442
current_received_ts = 77.111893
current_seq = 56
count = 51
seq_step = 1
[77.354319] (kms_flip:2123) DEBUG: Vblank took 35us
[77.373170] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.112442
last_received_ts = 77.111893
last_seq = 56
current_ts = 77.131409
current_received_ts = 77.130775
current_seq = 57
count = 52
seq_step = 1
[77.373220] (kms_flip:2123) DEBUG: Vblank took 34us
[77.392250] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.131409
last_received_ts = 77.130775
last_seq = 57
current_ts = 77.150360
current_received_ts = 77.149841
current_seq = 58
count = 53
seq_step = 1
[77.392301] (kms_flip:2123) DEBUG: Vblank took 34us
[77.411104] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.150360
last_received_ts = 77.149841
last_seq = 58
current_ts = 77.169319
current_received_ts = 77.168686
current_seq = 59
count = 54
seq_step = 1
[77.411154] (kms_flip:2123) DEBUG: Vblank took 33us
[77.430177] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.169319
last_received_ts = 77.168686
last_seq = 59
current_ts = 77.188286
current_received_ts = 77.187752
current_seq = 60
count = 55
seq_step = 1
[77.430229] (kms_flip:2123) DEBUG: Vblank took 35us
[77.449176] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.188286
last_received_ts = 77.187752
last_seq = 60
current_ts = 77.207237
current_received_ts = 77.206734
current_seq = 61
count = 56
seq_step = 1
[77.449228] (kms_flip:2123) DEBUG: Vblank took 35us
[77.468040] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.207237
last_received_ts = 77.206734
last_seq = 61
current_ts = 77.226212
current_received_ts = 77.225586
current_seq = 62
count = 57
seq_step = 1
[77.468090] (kms_flip:2123) DEBUG: Vblank took 34us
[77.487012] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.226212
last_received_ts = 77.225586
last_seq = 62
current_ts = 77.245171
current_received_ts = 77.244545
current_seq = 63
count = 58
seq_step = 1
[77.487061] (kms_flip:2123) DEBUG: Vblank took 33us
[77.505980] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.245171
last_received_ts = 77.244545
last_seq = 63
current_ts = 77.264130
current_received_ts = 77.263504
current_seq = 64
count = 59
seq_step = 1
[77.506031] (kms_flip:2123) DEBUG: Vblank took 35us
[77.524960] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.264130
last_received_ts = 77.263504
last_seq = 64
current_ts = 77.283096
current_received_ts = 77.282471
current_seq = 65
count = 60
seq_step = 1
[77.525011] (kms_flip:2123) DEBUG: Vblank took 34us
[77.543975] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.283096
last_received_ts = 77.282471
last_seq = 65
current_ts = 77.302040
current_received_ts = 77.301468
current_seq = 66
count = 61
seq_step = 1
[77.544025] (kms_flip:2123) DEBUG: Vblank took 34us
[77.562887] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.302040
last_received_ts = 77.301468
last_seq = 66
current_ts = 77.321007
current_received_ts = 77.320374
current_seq = 67
count = 62
seq_step = 1
[77.562937] (kms_flip:2123) DEBUG: Vblank took 33us
[77.581870] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.321007
last_received_ts = 77.320374
last_seq = 67
current_ts = 77.339966
current_received_ts = 77.339340
current_seq = 68
count = 63
seq_step = 1
[77.581920] (kms_flip:2123) DEBUG: Vblank took 33us
[77.600931] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.339966
last_received_ts = 77.339340
last_seq = 68
current_ts = 77.358925
current_received_ts = 77.358391
current_seq = 69
count = 64
seq_step = 1
[77.600982] (kms_flip:2123) DEBUG: Vblank took 35us
[77.619804] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.358925
last_received_ts = 77.358391
last_seq = 69
current_ts = 77.377899
current_received_ts = 77.377258
current_seq = 70
count = 65
seq_step = 1
[77.619847] (kms_flip:2123) DEBUG: Vblank took 30us
[77.638827] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.377899
last_received_ts = 77.377258
last_seq = 70
current_ts = 77.396858
current_received_ts = 77.396263
current_seq = 71
count = 66
seq_step = 1
[77.638875] (kms_flip:2123) DEBUG: Vblank took 33us
[77.657763] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.396858
last_received_ts = 77.396263
last_seq = 71
current_ts = 77.415817
current_received_ts = 77.415184
current_seq = 72
count = 67
seq_step = 1
[77.657813] (kms_flip:2123) DEBUG: Vblank took 33us
[77.676754] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.415817
last_received_ts = 77.415184
last_seq = 72
current_ts = 77.434784
current_received_ts = 77.434158
current_seq = 73
count = 68
seq_step = 1
[77.676804] (kms_flip:2123) DEBUG: Vblank took 34us
[77.695713] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.434784
last_received_ts = 77.434158
last_seq = 73
current_ts = 77.453743
current_received_ts = 77.453117
current_seq = 74
count = 69
seq_step = 1
[77.695760] (kms_flip:2123) DEBUG: Vblank took 32us
[77.714706] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.453743
last_received_ts = 77.453117
last_seq = 74
current_ts = 77.472702
current_received_ts = 77.472092
current_seq = 75
count = 70
seq_step = 1
[77.714757] (kms_flip:2123) DEBUG: Vblank took 34us
[77.733710] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.472702
last_received_ts = 77.472092
last_seq = 75
current_ts = 77.491669
current_received_ts = 77.491081
current_seq = 76
count = 71
seq_step = 1
[77.733761] (kms_flip:2123) DEBUG: Vblank took 35us
[77.752689] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.491669
last_received_ts = 77.491081
last_seq = 76
current_ts = 77.510628
current_received_ts = 77.510002
current_seq = 77
count = 72
seq_step = 1
[77.752741] (kms_flip:2123) DEBUG: Vblank took 34us
[77.771608] (kms_flip:2123) DEBUG: name = flip
last_ts = 77.510628
last_received_ts = 77.510002
last_seq = 77
current_ts = 77.529587
current_received_ts = 77.528954
current_seq = 78
count = 73
seq_step = 1
[77.772591] (kms_flip:2123) DEBUG: Vblank took 963us
[77.772600] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[77.772715] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500
[77.772765] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call
[77.776931] (kms_flip:2123) igt_core-INFO: Stack trace:
[77.780558] (kms_flip:2123) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[77.782746] (kms_flip:2123) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[77.782863] (kms_flip:2123) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[77.782948] (kms_flip:2123) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[77.782999] (kms_flip:2123) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[77.787609] (kms_flip:2123) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[77.789515] (kms_flip:2123) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[77.789892] (kms_flip:2123) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A2: FAIL (1.539s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (15.789s)
[77.811365] (kms_flip:2123) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
[77.811382] (kms_flip:2123) igt_kms-CRITICAL: Failed assertion: found_it
[77.811394] (kms_flip:2123) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[77.811404] (kms_flip:2123) igt_kms-CRITICAL: DPMS property not found on 400
Dmesg
<6> [62.032369] Console: switching to colour dummy device 80x25
<6> [62.032430] [IGT] kms_flip: executing
<7> [62.038188] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [62.038862] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [62.039080] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [62.044609] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [62.044618] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [62.049043] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [62.049455] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [62.049459] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [62.053698] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [62.054014] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [62.054018] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [62.058217] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<7> [62.058455] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [62.058458] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [62.062683] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [62.062695] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2]
<7> [62.062699] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.088625] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [62.088634] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [62.088638] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD monitor fitHeadless4k
<7> [62.088644] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD size 36, SAD count 0
<7> [62.088859] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [62.089031] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK on first message, retry
<7> [62.089414] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [62.089543] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [62.089897] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH)
<7> [62.089905] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [62.089921] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [62.089927] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2] probed modes:
<7> [62.089933] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [62.089936] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5
<7> [62.089939] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa
<7> [62.089942] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa
<7> [62.089945] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa
<7> [62.089948] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa
<7> [62.089951] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa
<7> [62.089954] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [62.089957] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [62.089960] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [62.089963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [62.089966] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [62.089969] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa
<7> [62.089972] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [62.089975] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [62.089978] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089980] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089983] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089986] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [62.089989] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [62.089992] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [62.089994] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [62.089997] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6
<7> [62.090000] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [62.090003] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [62.090006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [62.090008] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [62.090011] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090014] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090017] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090021] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [62.090050] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [62.090055] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [62.094336] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [62.094350] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [62.094353] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [62.098567] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<6> [62.102910] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [62.104616] [IGT] kms_flip: starting dynamic subtest A-HDMI-A2
<7> [62.104990] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:415]
<7> [62.105084] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:419]
<7> [62.151460] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [62.151742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [62.151970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [62.152112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [62.152250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [62.152414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [62.152558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [62.152762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [62.152912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [62.153045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [62.153182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [62.153325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [62.153495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [62.153673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [62.153802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [62.153931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [62.154061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [62.154215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [62.154414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [62.154587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [62.154716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [62.154845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [62.154974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [62.155102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [62.155297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [62.155479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [62.155617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [62.155750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [62.155882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [62.156012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [62.156171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [62.156369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [62.156504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [62.156635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [62.156767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [62.156915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [62.157104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [62.157234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [62.157378] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [62.157508] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [62.157678] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [62.157869] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [62.158057] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [62.158188] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [62.158333] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.158463] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.158592] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.158755] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.158951] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [62.159087] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [62.159219] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [62.159429] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [62.159647] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [62.159795] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [62.159925] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [62.160054] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [62.160187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [62.160399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [62.160545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [62.160676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [62.160806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [62.160935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [62.161103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [62.177279] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [62.196675] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [62.198653] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [62.199424] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [62.200126] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [62.201077] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 180000 kHz, VCO 720000 kHz, ref 24000 kHz, bypass 12000 kHz, voltage level 0
<7> [62.201834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [62.202398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [62.202877] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [62.203264] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [62.203816] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [62.204182] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [62.204607] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.205345] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [62.205776] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [62.206279] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [62.208071] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [62.208317] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [62.208515] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [62.208547] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [62.208879] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [62.209106] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [62.209306] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [62.209555] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [62.209859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [62.210070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [62.210274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [62.210547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [62.210868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [62.211079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [62.211280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [62.211866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [62.212028] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [62.212168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [62.212307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [62.212490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [62.212679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [62.212824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [62.212964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [62.213104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [62.213299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [62.213461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [62.213610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [62.213748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [62.213885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [62.214084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [62.214284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [62.214440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [62.214570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [62.214717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [62.214847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [62.215029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [62.215188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [62.215316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [62.215444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [62.215572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [62.215748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [62.215926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [62.216056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [62.216184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [62.216312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [62.216445] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [62.216678] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [62.216816] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [62.216966] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [62.217093] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [62.217221] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.217405] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.217580] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.217731] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [62.217871] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [62.218007] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [62.218141] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [62.218339] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [62.218515] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [62.218662] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [62.218800] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [62.218937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [62.219120] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [62.219278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [62.219410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [62.219540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [62.219745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [62.219943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [62.220075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [62.220203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [62.220330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [62.220458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [62.220647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [62.220840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [62.220974] 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> [62.221103] 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> [62.221231] 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> [62.221361] 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> [62.221534] 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> [62.221720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [62.221851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [62.221980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [62.222110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [62.222272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [62.222446] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [62.222575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [62.222718] 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> [62.222847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [62.222989] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [62.223179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [62.223308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [62.223885] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [62.224056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [62.224571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:415] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [62.224757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [62.224950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [62.225101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [62.225229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [62.225356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [62.225504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [62.225671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [62.227454] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [62.227612] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [62.227769] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [62.227951] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [62.228087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [62.228219] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [62.228349] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [62.228557] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [62.228839] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [62.228976] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [62.229299] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [62.229539] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [62.249003] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [62.249190] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [62.268379] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.269310] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<6> [66.179993] [IGT] kms_flip: finished subtest A-HDMI-A2, FAIL
<6> [66.180508] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [66.180808] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:420]
<7> [66.180892] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:421]
<7> [66.196532] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [66.196613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [66.196786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [66.196923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [66.197056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [66.197196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [66.197327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [66.197457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [66.197586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [66.197715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [66.197844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [66.197972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [66.198100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [66.198277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [66.198407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [66.198535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [66.198667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [66.198796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [66.198925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [66.199052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [66.199180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [66.199320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [66.199447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [66.199575] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [66.199702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [66.199831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [66.199963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [66.200094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [66.200230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [66.200359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [66.200487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [66.200616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [66.200743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [66.200871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [66.201000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [66.201127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [66.201264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [66.201419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [66.201548] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [66.201677] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [66.201834] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [66.201964] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [66.202111] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [66.202292] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [66.202427] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [66.202556] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [66.202684] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [66.202811] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [66.202952] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [66.203086] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [66.203228] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [66.203404] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [66.203550] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [66.203684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [66.203817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [66.203949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [66.204080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [66.204219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [66.204367] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [66.204496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [66.214573] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [66.235370] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [66.235534] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [66.235694] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [66.235840] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [66.235981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [66.236115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [66.236258] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [66.236401] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [66.236555] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [66.236686] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [66.236816] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [66.237008] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [66.237186] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [66.237358] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [66.237692] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [66.237797] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [66.237883] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [66.237902] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [66.237973] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [66.238122] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [66.238249] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [66.238400] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:269:pipe B]
<7> [66.238685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [66.238822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [66.238957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [66.239092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [66.239225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [66.239369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [66.239519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [66.239651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [66.239779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [66.239909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [66.240037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [66.240164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [66.240292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [66.240437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [66.240605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [66.240733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [66.240862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [66.240990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [66.241118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [66.241246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [66.241389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [66.241537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [66.241665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [66.241792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [66.241919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [66.242047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [66.242175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [66.242302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [66.242445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [66.242594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [66.242721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [66.242850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [66.242977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [66.243106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in dpll_hw_state
<7> [66.243233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [66.243370] 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> [66.243525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [66.243660] 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> [66.243793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [66.243924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [66.244053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [66.244182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [66.244311] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [66.244483] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [66.244626] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [66.244778] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:153:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [66.244906] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [66.245033] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [66.245160] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [66.245287] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [66.245424] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [66.245587] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:269:pipe B] data rate 559716 num active planes 1
<7> [66.245720] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [66.245851] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [66.245993] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 0 kHz -> 69965 kHz
<7> [66.246134] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [66.246266] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:269:pipe B] allocated DPLL 0
<7> [66.246414] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:269:pipe B] reserving DPLL 0
<7> [66.246575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: yes [modeset]
<7> [66.246708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [66.246841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [66.246972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [66.247102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [66.247233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [66.247369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [66.247522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [66.247654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [66.247785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [66.247914] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [66.248042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [66.248169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [66.248297] 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> [66.248441] 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> [66.248588] 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> [66.248717] 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> [66.248844] 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> [66.248972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [66.249097] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [66.249223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [66.249357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [66.249509] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [66.249636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [66.249763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [66.249889] 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> [66.250016] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [66.250143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [66.250269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [66.250408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [66.250938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [66.251063] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251453] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [66.251606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [FB:420] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [66.251738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [66.251869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [66.251999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [66.252126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [66.252253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [66.252390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [66.252545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [66.253136] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [66.253296] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [66.253504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [66.253636] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [66.253767] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [66.253898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [66.254028] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [66.254192] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [66.254360] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:269:pipe B]
<7> [66.254514] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [66.254866] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [66.255040] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [66.293913] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [66.294129] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<6> [66.418359] pcieport 0000:00:06.0: AER: Correctable error message received from 0000:01:00.0
<4> [66.418379] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [66.418387] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [66.418395] nvme 0000:01:00.0: [ 0] RxErr (First)
<7> [76.331308] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:421] from all active usage due to RMFB ioctl
<7> [76.332226] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:153:plane 1B] because [FB:421] is removed
<7> [76.332469] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:153:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [76.333514] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [76.335786] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [76.336690] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [76.337723] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153: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> [76.339542] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:269:pipe B] data rate 0 num active planes 0
<7> [76.340582] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [76.341590] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [76.351848] [IGT] kms_flip: finished subtest B-HDMI-A2, SUCCESS
<6> [76.352961] [IGT] kms_flip: starting dynamic subtest C-HDMI-A2
<7> [76.353503] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:417]
<7> [76.353674] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:420]
<7> [76.370873] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [76.371013] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [76.371072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [76.371244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [76.371380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [76.371511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [76.371645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [76.371775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [76.371940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [76.372071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [76.372202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [76.372332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [76.372463] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [76.372593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [76.372722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [76.372892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [76.373026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [76.373155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [76.373285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [76.373414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [76.373543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [76.373671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [76.373805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [76.373988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [76.374116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [76.374244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [76.374374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [76.374503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [76.374632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [76.374760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [76.374906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [76.375035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [76.375164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [76.375294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [76.375423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [76.375554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [76.375682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [76.375815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [76.375944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [76.376073] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [76.376203] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:269:pipe B] releasing DPLL 0
<7> [76.376355] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [76.376487] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [76.376636] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [76.376783] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 69965 kHz -> 0 kHz
<7> [76.376932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: no [modeset]
<7> [76.377066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [76.377198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [76.377331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [76.377462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [76.377591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [76.377719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [76.377946] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [76.389064] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [76.389229] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [76.389390] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [76.389537] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [76.389674] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [76.389834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [76.389965] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [76.390095] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [76.390226] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [76.390355] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [76.390546] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [76.390722] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [76.390900] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<7> [76.391199] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [76.391326] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [76.391345] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [76.391398] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [76.391538] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [76.391664] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [76.391808] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:387:pipe C]
<7> [76.391942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [76.392078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [76.392210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [76.392342] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [76.392472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [76.392603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [76.392733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [76.392879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [76.393010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [76.393139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [76.393269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [76.393397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [76.393526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [76.393654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [76.393783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [76.393928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [76.394057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [76.394193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [76.394321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [76.394449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [76.394578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [76.394705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [76.394847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [76.394976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [76.395103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [76.395230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [76.395358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [76.395485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [76.395614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [76.395742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [76.395883] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [76.396011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [76.396140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [76.396268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in dpll_hw_state
<7> [76.396395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [76.396523] 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> [76.396652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [76.396780] 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> [76.396919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [76.397047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [76.397175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [76.397302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [76.397430] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [76.397577] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [76.397707] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [76.397870] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [76.397999] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [76.398126] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [76.398253] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [76.398380] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [76.398507] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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> [76.398650] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 559716 num active planes 1
<7> [76.398786] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [76.398931] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [76.399076] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 69965 kHz
<7> [76.399220] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:387:pipe C] allocated DPLL 0
<7> [76.399357] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:387:pipe C] reserving DPLL 0
<7> [76.399492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [76.399626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [76.399758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [76.399898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [76.400027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [76.400157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [76.400285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [76.400414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [76.400543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [76.400673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [76.400800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [76.400941] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [76.401068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [76.401196] 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> [76.401326] 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> [76.401454] 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> [76.401583] 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> [76.401711] 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> [76.401848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [76.401979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [76.402107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [76.402235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [76.402362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [76.402490] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [76.402618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [76.402746] 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> [76.402884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [76.403012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [76.403138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [76.403265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [76.403773] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [76.403909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404163] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [76.404417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [FB:417] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [76.404547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [76.404673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [76.404801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [76.404940] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [76.405067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [76.405193] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [76.405320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [76.406133] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [76.406291] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [76.406453] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [76.406590] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [76.406724] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [76.406870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [76.407001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [76.407132] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [76.407299] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [76.407467] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [76.407603] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [76.407795] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [76.407980] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [76.447078] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [76.447895] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<6> [77.891639] [IGT] kms_flip: finished subtest C-HDMI-A2, FAIL
<6> [77.892530] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [77.895555] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:420] from all active usage due to RMFB ioctl
<7> [77.895709] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:420] is removed
<7> [77.895749] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [77.896066] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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.896209] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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.896346] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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.896482] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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.896640] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [77.896781] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [77.896927] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [77.909459] [IGT] kms_flip: exiting, ret=98
<7> [77.920168] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [77.920355] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [77.920489] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [77.920641] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [77.920772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [77.920927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [77.921078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [77.921212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [77.921344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [77.921476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [77.921606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [77.921738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [77.921868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [77.922014] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [77.922158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [77.922289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [77.922419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [77.922549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [77.922679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [77.922808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [77.922957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [77.923097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [77.923226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [77.923355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [77.923484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [77.923612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [77.923741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [77.923869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [77.924118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [77.924249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [77.924379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [77.924508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [77.924637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [77.924765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [77.924895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [77.925045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [77.925187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [77.925316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [77.925445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [77.925574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [77.925703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [77.925832] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [77.925978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [77.926124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [77.926256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [77.926388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [77.926519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [77.926650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [77.926781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [77.926911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [77.927063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [77.927205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [77.927334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [77.927462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [77.927608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [77.927737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [77.927866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [77.928010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [77.928152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [77.928280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [77.928407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [77.928535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [77.928663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [77.928790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [77.928927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [77.929069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [77.929197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [77.929325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [77.929452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [77.929579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [77.929707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [77.929835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [77.930056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [77.930185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [77.930313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [77.930443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [77.930570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [77.930698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [77.930827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [77.931010] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [77.931205] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [77.931450] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x1
<7> [77.931644] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [77.931833] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [77.932030] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [77.932162] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.932293] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.932419] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.932545] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.932670] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [77.932815] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [77.932962] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [77.933093] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [77.933239] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [77.933371] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 69965 kHz -> 0 kHz
<7> [77.933517] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [77.933653] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [77.933792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [77.933935] 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.934068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [77.934199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [77.934328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [77.934458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [77.934587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [77.934717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [77.934846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [77.934993] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [77.935121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [77.935249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [77.935377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [77.935504] 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.935632] 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.935760] 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.935889] 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.936040] 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.936168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [77.936296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [77.936423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [77.936555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [77.936686] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [77.936817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [77.936954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [77.937083] 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.937212] 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.937340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [77.937468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.937595] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937849] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.938115] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.938242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938368] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.938746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:416] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [77.938874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [77.939011] 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.939138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [77.939264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [77.939391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [77.939516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [77.939642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [77.939768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [77.939894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [77.940039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [77.940169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [77.940297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [77.940426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [77.940553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [77.941044] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [77.946022] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [77.946286] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [77.946502] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [77.946652] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [77.946789] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [77.946923] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [77.947076] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [77.947207] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [77.947336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [77.947598] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [77.947733] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [77.948060] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [77.948243] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [77.967729] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [77.967877] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [77.986793] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [77.987024] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [77.987570] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [77.987817] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [77.988207] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-05-19 15:56:45