Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-dp2-hdmi-a3

Result: Fail

i915_display_info0 igt_runner0 results0.json results0-xe-load.json guc_logs0.tar boot0 dmesg0

DetailValue
Duration 4.26 seconds
Hostname
shard-bmg-10
Igt-Version
IGT-Version: 2.4-g5b279a8b7 (x86_64) (Linux: 7.0.0-lgci-xe-xe-4917-ff84b38d86b994ebb-debug+ x86_64)
Out
Starting dynamic subtest: AC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2193 __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 AC-DP2-HDMI-A3: FAIL (4.260s)
Err
Starting dynamic subtest: AC-DP2-HDMI-A3
(kms_flip:4838) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4838) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:4838) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4838) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4838) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4838) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4838) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 181.413132
current_received_ts = 181.412842
current_seq = 413
count = 0
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 38us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.413132
last_received_ts = 181.412842
last_seq = 413
current_ts = 181.429794
current_received_ts = 181.429428
current_seq = 414
count = 1
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 38us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.429794
last_received_ts = 181.429428
last_seq = 414
current_ts = 181.446472
current_received_ts = 181.446152
current_seq = 415
count = 2
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 27us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.446472
last_received_ts = 181.446152
last_seq = 415
current_ts = 181.463135
current_received_ts = 181.462601
current_seq = 416
count = 3
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.463135
last_received_ts = 181.462601
last_seq = 416
current_ts = 181.479813
current_received_ts = 181.479263
current_seq = 417
count = 4
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.479813
last_received_ts = 181.479263
last_seq = 417
current_ts = 181.496475
current_received_ts = 181.495926
current_seq = 418
count = 5
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.496475
last_received_ts = 181.495926
last_seq = 418
current_ts = 181.513123
current_received_ts = 181.512604
current_seq = 419
count = 6
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.513123
last_received_ts = 181.512604
last_seq = 419
current_ts = 181.529816
current_received_ts = 181.529266
current_seq = 420
count = 7
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.529816
last_received_ts = 181.529266
last_seq = 420
current_ts = 181.546478
current_received_ts = 181.545944
current_seq = 421
count = 8
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.546478
last_received_ts = 181.545944
last_seq = 421
current_ts = 181.563141
current_received_ts = 181.562607
current_seq = 422
count = 9
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.563141
last_received_ts = 181.562607
last_seq = 422
current_ts = 181.579803
current_received_ts = 181.579269
current_seq = 423
count = 10
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.579803
last_received_ts = 181.579269
last_seq = 423
current_ts = 181.596481
current_received_ts = 181.595932
current_seq = 424
count = 11
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.596481
last_received_ts = 181.595932
last_seq = 424
current_ts = 181.613144
current_received_ts = 181.612610
current_seq = 425
count = 12
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.613144
last_received_ts = 181.612610
last_seq = 425
current_ts = 181.629807
current_received_ts = 181.629272
current_seq = 426
count = 13
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.629807
last_received_ts = 181.629272
last_seq = 426
current_ts = 181.646484
current_received_ts = 181.645935
current_seq = 427
count = 14
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.646484
last_received_ts = 181.645935
last_seq = 427
current_ts = 181.663147
current_received_ts = 181.662613
current_seq = 428
count = 15
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.663147
last_received_ts = 181.662613
last_seq = 428
current_ts = 181.679810
current_received_ts = 181.679504
current_seq = 429
count = 16
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.679810
last_received_ts = 181.679504
last_seq = 429
current_ts = 181.696472
current_received_ts = 181.696167
current_seq = 430
count = 17
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.696472
last_received_ts = 181.696167
last_seq = 430
current_ts = 181.713150
current_received_ts = 181.712845
current_seq = 431
count = 18
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.713150
last_received_ts = 181.712845
last_seq = 431
current_ts = 181.729813
current_received_ts = 181.729507
current_seq = 432
count = 19
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.729813
last_received_ts = 181.729507
last_seq = 432
current_ts = 181.746490
current_received_ts = 181.746170
current_seq = 433
count = 20
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.746490
last_received_ts = 181.746170
last_seq = 433
current_ts = 181.763153
current_received_ts = 181.762619
current_seq = 434
count = 21
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.763153
last_received_ts = 181.762619
last_seq = 434
current_ts = 181.779816
current_received_ts = 181.779282
current_seq = 435
count = 22
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.779816
last_received_ts = 181.779282
last_seq = 435
current_ts = 181.796494
current_received_ts = 181.795959
current_seq = 436
count = 23
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.796494
last_received_ts = 181.795959
last_seq = 436
current_ts = 181.813156
current_received_ts = 181.812622
current_seq = 437
count = 24
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.813156
last_received_ts = 181.812622
last_seq = 437
current_ts = 181.829819
current_received_ts = 181.829285
current_seq = 438
count = 25
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.829819
last_received_ts = 181.829285
last_seq = 438
current_ts = 181.846497
current_received_ts = 181.845963
current_seq = 439
count = 26
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 40us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.846497
last_received_ts = 181.845963
last_seq = 439
current_ts = 181.863159
current_received_ts = 181.862625
current_seq = 440
count = 27
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.863159
last_received_ts = 181.862625
last_seq = 440
current_ts = 181.879822
current_received_ts = 181.879288
current_seq = 441
count = 28
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.879822
last_received_ts = 181.879288
last_seq = 441
current_ts = 181.896500
current_received_ts = 181.895966
current_seq = 442
count = 29
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 40us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.896500
last_received_ts = 181.895966
last_seq = 442
current_ts = 181.913162
current_received_ts = 181.912628
current_seq = 443
count = 30
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.913162
last_received_ts = 181.912628
last_seq = 443
current_ts = 181.929825
current_received_ts = 181.929291
current_seq = 444
count = 31
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.929825
last_received_ts = 181.929291
last_seq = 444
current_ts = 181.946503
current_received_ts = 181.945953
current_seq = 445
count = 32
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.946503
last_received_ts = 181.945953
last_seq = 445
current_ts = 181.963165
current_received_ts = 181.962631
current_seq = 446
count = 33
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.963165
last_received_ts = 181.962631
last_seq = 446
current_ts = 181.979828
current_received_ts = 181.979523
current_seq = 447
count = 34
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.979828
last_received_ts = 181.979523
last_seq = 447
current_ts = 181.996506
current_received_ts = 181.996185
current_seq = 448
count = 35
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 181.996506
last_received_ts = 181.996185
last_seq = 448
current_ts = 182.013168
current_received_ts = 182.012848
current_seq = 449
count = 36
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.013168
last_received_ts = 182.012848
last_seq = 449
current_ts = 182.029831
current_received_ts = 182.029526
current_seq = 450
count = 37
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.029831
last_received_ts = 182.029526
last_seq = 450
current_ts = 182.046509
current_received_ts = 182.046188
current_seq = 451
count = 38
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.046509
last_received_ts = 182.046188
last_seq = 451
current_ts = 182.063171
current_received_ts = 182.062637
current_seq = 452
count = 39
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.063171
last_received_ts = 182.062637
last_seq = 452
current_ts = 182.079834
current_received_ts = 182.079300
current_seq = 453
count = 40
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.079834
last_received_ts = 182.079300
last_seq = 453
current_ts = 182.096512
current_received_ts = 182.095963
current_seq = 454
count = 41
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 39us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.096512
last_received_ts = 182.095963
last_seq = 454
current_ts = 182.113174
current_received_ts = 182.112717
current_seq = 455
count = 42
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 33us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.113174
last_received_ts = 182.112717
last_seq = 455
current_ts = 182.129837
current_received_ts = 182.129272
current_seq = 456
count = 43
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 32us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.129837
last_received_ts = 182.129272
last_seq = 456
current_ts = 182.146500
current_received_ts = 182.145905
current_seq = 457
count = 44
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 28us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.146500
last_received_ts = 182.145905
last_seq = 457
current_ts = 182.163162
current_received_ts = 182.162582
current_seq = 458
count = 45
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.163162
last_received_ts = 182.162582
last_seq = 458
current_ts = 182.179840
current_received_ts = 182.179245
current_seq = 459
count = 46
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.179840
last_received_ts = 182.179245
last_seq = 459
current_ts = 182.196503
current_received_ts = 182.195908
current_seq = 460
count = 47
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.196503
last_received_ts = 182.195908
last_seq = 460
current_ts = 182.213165
current_received_ts = 182.212570
current_seq = 461
count = 48
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.213165
last_received_ts = 182.212570
last_seq = 461
current_ts = 182.229843
current_received_ts = 182.229248
current_seq = 462
count = 49
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.229843
last_received_ts = 182.229248
last_seq = 462
current_ts = 182.246506
current_received_ts = 182.245911
current_seq = 463
count = 50
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.246506
last_received_ts = 182.245911
last_seq = 463
current_ts = 182.263168
current_received_ts = 182.262573
current_seq = 464
count = 51
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.263168
last_received_ts = 182.262573
last_seq = 464
current_ts = 182.279846
current_received_ts = 182.279465
current_seq = 465
count = 52
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.279846
last_received_ts = 182.279465
last_seq = 465
current_ts = 182.296509
current_received_ts = 182.296143
current_seq = 466
count = 53
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.296509
last_received_ts = 182.296143
last_seq = 466
current_ts = 182.313171
current_received_ts = 182.312805
current_seq = 467
count = 54
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.313171
last_received_ts = 182.312805
last_seq = 467
current_ts = 182.329849
current_received_ts = 182.329468
current_seq = 468
count = 55
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.329849
last_received_ts = 182.329468
last_seq = 468
current_ts = 182.346512
current_received_ts = 182.346146
current_seq = 469
count = 56
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.346512
last_received_ts = 182.346146
last_seq = 469
current_ts = 182.363174
current_received_ts = 182.362579
current_seq = 470
count = 57
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.363174
last_received_ts = 182.362579
last_seq = 470
current_ts = 182.379852
current_received_ts = 182.379257
current_seq = 471
count = 58
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.379852
last_received_ts = 182.379257
last_seq = 471
current_ts = 182.396515
current_received_ts = 182.395920
current_seq = 472
count = 59
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.396515
last_received_ts = 182.395920
last_seq = 472
current_ts = 182.413177
current_received_ts = 182.412582
current_seq = 473
count = 60
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.413177
last_received_ts = 182.412582
last_seq = 473
current_ts = 182.429855
current_received_ts = 182.429260
current_seq = 474
count = 61
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.429855
last_received_ts = 182.429260
last_seq = 474
current_ts = 182.446518
current_received_ts = 182.445923
current_seq = 475
count = 62
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.446518
last_received_ts = 182.445923
last_seq = 475
current_ts = 182.463181
current_received_ts = 182.462585
current_seq = 476
count = 63
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.463181
last_received_ts = 182.462585
last_seq = 476
current_ts = 182.479858
current_received_ts = 182.479263
current_seq = 477
count = 64
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.479858
last_received_ts = 182.479263
last_seq = 477
current_ts = 182.496521
current_received_ts = 182.495926
current_seq = 478
count = 65
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.496521
last_received_ts = 182.495926
last_seq = 478
current_ts = 182.513184
current_received_ts = 182.512589
current_seq = 479
count = 66
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.513184
last_received_ts = 182.512589
last_seq = 479
current_ts = 182.529861
current_received_ts = 182.529266
current_seq = 480
count = 67
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.529861
last_received_ts = 182.529266
last_seq = 480
current_ts = 182.546524
current_received_ts = 182.545929
current_seq = 481
count = 68
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.546524
last_received_ts = 182.545929
last_seq = 481
current_ts = 182.563187
current_received_ts = 182.562592
current_seq = 482
count = 69
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.563187
last_received_ts = 182.562592
last_seq = 482
current_ts = 182.579880
current_received_ts = 182.579483
current_seq = 483
count = 70
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.579880
last_received_ts = 182.579483
last_seq = 483
current_ts = 182.596527
current_received_ts = 182.596161
current_seq = 484
count = 71
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.596527
last_received_ts = 182.596161
last_seq = 484
current_ts = 182.613205
current_received_ts = 182.612823
current_seq = 485
count = 72
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.613205
last_received_ts = 182.612823
last_seq = 485
current_ts = 182.629868
current_received_ts = 182.629486
current_seq = 486
count = 73
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.629868
last_received_ts = 182.629486
last_seq = 486
current_ts = 182.646530
current_received_ts = 182.646164
current_seq = 487
count = 74
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.646530
last_received_ts = 182.646164
last_seq = 487
current_ts = 182.663193
current_received_ts = 182.662598
current_seq = 488
count = 75
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.663193
last_received_ts = 182.662598
last_seq = 488
current_ts = 182.679871
current_received_ts = 182.679276
current_seq = 489
count = 76
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.679871
last_received_ts = 182.679276
last_seq = 489
current_ts = 182.696533
current_received_ts = 182.695938
current_seq = 490
count = 77
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.696533
last_received_ts = 182.695938
last_seq = 490
current_ts = 182.713196
current_received_ts = 182.712601
current_seq = 491
count = 78
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.713196
last_received_ts = 182.712601
last_seq = 491
current_ts = 182.729874
current_received_ts = 182.729279
current_seq = 492
count = 79
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.729874
last_received_ts = 182.729279
last_seq = 492
current_ts = 182.746536
current_received_ts = 182.745941
current_seq = 493
count = 80
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.746536
last_received_ts = 182.745941
last_seq = 493
current_ts = 182.763199
current_received_ts = 182.762604
current_seq = 494
count = 81
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 28us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.763199
last_received_ts = 182.762604
last_seq = 494
current_ts = 182.779877
current_received_ts = 182.779282
current_seq = 495
count = 82
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.779877
last_received_ts = 182.779282
last_seq = 495
current_ts = 182.796539
current_received_ts = 182.795944
current_seq = 496
count = 83
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.796539
last_received_ts = 182.795944
last_seq = 496
current_ts = 182.813202
current_received_ts = 182.812607
current_seq = 497
count = 84
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.813202
last_received_ts = 182.812607
last_seq = 497
current_ts = 182.829880
current_received_ts = 182.829285
current_seq = 498
count = 85
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.829880
last_received_ts = 182.829285
last_seq = 498
current_ts = 182.846542
current_received_ts = 182.845947
current_seq = 499
count = 86
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.846542
last_received_ts = 182.845947
last_seq = 499
current_ts = 182.863205
current_received_ts = 182.862610
current_seq = 500
count = 87
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.863205
last_received_ts = 182.862610
last_seq = 500
current_ts = 182.879883
current_received_ts = 182.879501
current_seq = 501
count = 88
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.879883
last_received_ts = 182.879501
last_seq = 501
current_ts = 182.896545
current_received_ts = 182.896179
current_seq = 502
count = 89
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.896545
last_received_ts = 182.896179
last_seq = 502
current_ts = 182.913223
current_received_ts = 182.912842
current_seq = 503
count = 90
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.913223
last_received_ts = 182.912842
last_seq = 503
current_ts = 182.929886
current_received_ts = 182.929520
current_seq = 504
count = 91
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.929886
last_received_ts = 182.929520
last_seq = 504
current_ts = 182.946564
current_received_ts = 182.946167
current_seq = 505
count = 92
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.946564
last_received_ts = 182.946167
last_seq = 505
current_ts = 182.963211
current_received_ts = 182.962616
current_seq = 506
count = 93
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.963211
last_received_ts = 182.962616
last_seq = 506
current_ts = 182.979889
current_received_ts = 182.979294
current_seq = 507
count = 94
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.979889
last_received_ts = 182.979294
last_seq = 507
current_ts = 182.996552
current_received_ts = 182.995956
current_seq = 508
count = 95
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 182.996552
last_received_ts = 182.995956
last_seq = 508
current_ts = 183.013214
current_received_ts = 183.012619
current_seq = 509
count = 96
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.013214
last_received_ts = 183.012619
last_seq = 509
current_ts = 183.029892
current_received_ts = 183.029297
current_seq = 510
count = 97
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.029892
last_received_ts = 183.029297
last_seq = 510
current_ts = 183.046555
current_received_ts = 183.045959
current_seq = 511
count = 98
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.046555
last_received_ts = 183.045959
last_seq = 511
current_ts = 183.063217
current_received_ts = 183.062622
current_seq = 512
count = 99
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.063217
last_received_ts = 183.062622
last_seq = 512
current_ts = 183.079895
current_received_ts = 183.079300
current_seq = 513
count = 100
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.079895
last_received_ts = 183.079300
last_seq = 513
current_ts = 183.096558
current_received_ts = 183.095963
current_seq = 514
count = 101
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.096558
last_received_ts = 183.095963
last_seq = 514
current_ts = 183.113220
current_received_ts = 183.112625
current_seq = 515
count = 102
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.113220
last_received_ts = 183.112625
last_seq = 515
current_ts = 183.129898
current_received_ts = 183.129303
current_seq = 516
count = 103
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.129898
last_received_ts = 183.129303
last_seq = 516
current_ts = 183.146561
current_received_ts = 183.145966
current_seq = 517
count = 104
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 40us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.146561
last_received_ts = 183.145966
last_seq = 517
current_ts = 183.163223
current_received_ts = 183.162628
current_seq = 518
count = 105
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.163223
last_received_ts = 183.162628
last_seq = 518
current_ts = 183.179901
current_received_ts = 183.179520
current_seq = 519
count = 106
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.179901
last_received_ts = 183.179520
last_seq = 519
current_ts = 183.196564
current_received_ts = 183.196198
current_seq = 520
count = 107
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.196564
last_received_ts = 183.196198
last_seq = 520
current_ts = 183.213242
current_received_ts = 183.212860
current_seq = 521
count = 108
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.213242
last_received_ts = 183.212860
last_seq = 521
current_ts = 183.229904
current_received_ts = 183.229523
current_seq = 522
count = 109
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.229904
last_received_ts = 183.229523
last_seq = 522
current_ts = 183.246567
current_received_ts = 183.246201
current_seq = 523
count = 110
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.246567
last_received_ts = 183.246201
last_seq = 523
current_ts = 183.263229
current_received_ts = 183.262634
current_seq = 524
count = 111
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 27us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.263229
last_received_ts = 183.262634
last_seq = 524
current_ts = 183.279907
current_received_ts = 183.279312
current_seq = 525
count = 112
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.279907
last_received_ts = 183.279312
last_seq = 525
current_ts = 183.296570
current_received_ts = 183.295975
current_seq = 526
count = 113
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 42us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.296570
last_received_ts = 183.295975
last_seq = 526
current_ts = 183.313232
current_received_ts = 183.312637
current_seq = 527
count = 114
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.313232
last_received_ts = 183.312637
last_seq = 527
current_ts = 183.329910
current_received_ts = 183.329315
current_seq = 528
count = 115
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 26us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.329910
last_received_ts = 183.329315
last_seq = 528
current_ts = 183.346573
current_received_ts = 183.345978
current_seq = 529
count = 116
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 44us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.346573
last_received_ts = 183.345978
last_seq = 529
current_ts = 183.363235
current_received_ts = 183.362640
current_seq = 530
count = 117
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.363235
last_received_ts = 183.362640
last_seq = 530
current_ts = 183.379913
current_received_ts = 183.379318
current_seq = 531
count = 118
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 25us
(kms_flip:4838) DEBUG: name = flip
last_ts = 183.379913
last_received_ts = 183.379318
last_seq = 531
current_ts = 183.396576
current_received_ts = 183.395981
current_seq = 532
count = 119
seq_step = 1
(kms_flip:4838) DEBUG: Vblank took 1745us
(kms_flip:4838) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4838) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4838) igt_core-INFO: Stack trace:
(kms_flip:4838) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4838) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:4838) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:4838) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
(kms_flip:4838) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4838) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4838) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4838) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-DP2-HDMI-A3: FAIL (4.260s)
Dmesg
<6> [181.352979] [IGT] kms_flip: starting dynamic subtest AC-DP2-HDMI-A3
<7> [181.354475] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:555]
<7> [181.355272] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [181.418613] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [181.418812] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [181.419148] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [181.419429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [181.419703] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [181.419952] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [181.420219] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [181.420451] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [181.420672] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.420890] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [181.421120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.421333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.421541] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.421744] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.421939] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.422162] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.422359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.422544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.422728] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.422920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.423133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.423315] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [181.423496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.423671] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.423840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.424009] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.424171] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.424333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.424497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.424645] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.424797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.424947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.425109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [181.425254] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [181.425397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [181.425537] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [181.425676] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [181.425809] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [181.425951] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [181.426107] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [181.426245] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [181.426382] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [181.426537] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [181.426649] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x5 -> 0x4
<7> [181.426780] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [181.426901] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [181.427061] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [181.427167] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [181.427267] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [181.427366] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [181.427464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [181.427563] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [181.427665] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [181.428349] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [181.449982] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [181.450673] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [181.468130] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [181.470864] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [181.473275] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [181.473999] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.474482] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.474984] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.475444] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.475326] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.475887] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.476383] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.476919] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.477416] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.477863] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.478310] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.478707] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.479103] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.479455] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.479797] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.480144] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.480458] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.480765] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [181.482837] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.483187] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [181.483728] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [181.484089] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [181.484476] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [181.485012] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [181.485158] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [181.485388] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [181.485601] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [181.485795] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [181.485993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [181.486179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [181.486364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.486533] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [181.486693] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.486851] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.487018] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.487169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.487319] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.487469] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.487606] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.487739] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.487868] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.488007] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.488132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.488259] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [181.488387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.488509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.488626] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.488743] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.488859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.488988] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.489102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.489215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.489325] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.489434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.489536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [181.489635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [181.489735] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [181.489833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [181.489931] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [181.490035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [181.490131] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [181.490226] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [181.490321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [181.490415] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [181.490510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [181.490605] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in avi infoframe
<7> [181.490701] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.490795] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.490797] xe 0000:03:00.0: colorspace: RGB
<7> [181.490798] xe 0000:03:00.0: scan mode: Underscan
<7> [181.490800] xe 0000:03:00.0: colorimetry: No Data
<7> [181.490801] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.490802] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.490803] xe 0000:03:00.0: itc: No Data
<7> [181.490805] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.490806] xe 0000:03:00.0: quantization range: Limited
<7> [181.490807] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.490808] xe 0000:03:00.0: video code: 16
<7> [181.490810] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.490811] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.490812] xe 0000:03:00.0: pixel repeat: 0
<7> [181.490813] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.490815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.490910] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in spd infoframe
<7> [181.491013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.491108] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.491109] xe 0000:03:00.0: vendor: Intel
<7> [181.491111] xe 0000:03:00.0: product: Discrete gfx
<7> [181.491112] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.491113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.491207] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hdmi infoframe
<7> [181.491303] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.491404] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.491405] xe 0000:03:00.0: empty frame
<7> [181.491406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.491506] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [181.491604] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe C] releasing TC PLL 3
<7> [181.491718] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [181.491803] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [181.491904] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [181.492010] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [181.492140] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: no [modeset]
<7> [181.492244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [NOFB], visible: no
<7> [181.492342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [181.492441] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [181.492537] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [181.492634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [181.492728] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [181.493132] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:269:pipe C]
<7> [181.526439] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [181.527903] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [181.539664] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.544020] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x4, on? 1) for [CRTC:269:pipe C]
<7> [181.546640] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [181.547548] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.548113] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.548617] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.549127] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.549563] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.550003] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.550430] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.550832] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.551265] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.551622] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.551992] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.552552] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.552871] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.553183] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.553473] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.553770] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.554068] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [181.554369] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.554723] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [181.555310] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [181.555791] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [181.556281] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [181.556757] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [181.557361] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [181.558121] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [181.558452] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [181.558484] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [181.558572] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [181.558752] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [181.558928] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:387:pipe B]
<7> [181.559115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [181.559285] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [181.559448] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [181.559596] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [181.559740] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [181.559882] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [181.560027] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.560164] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [181.560301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.560432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.560558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.560682] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.560805] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.560925] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.561054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.561174] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.561291] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.561407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.561524] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.561630] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [181.561737] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.561841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.561943] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.562055] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.562156] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.562258] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.562359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.562460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.562561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.562662] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.562763] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [181.562863] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [181.562971] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [181.563078] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [181.563184] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [181.563290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in dpll_hw_state
<7> [181.563394] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.563496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.563599] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [181.563700] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [181.563802] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.563903] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [181.564012] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [181.564113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [181.564215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [181.564317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [181.564421] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [181.564521] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [181.564623] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [181.564724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [181.564825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [181.564926] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [181.565034] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.565132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.565234] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.565334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [181.565435] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [181.565535] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.565636] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [181.565737] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [181.565838] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [181.565939] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [181.566050] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [181.566151] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [181.566252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [181.566354] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [181.566455] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [181.566556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [181.566656] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [181.566756] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.566862] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [181.566975] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [181.567085] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [181.567188] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [181.567292] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [181.567395] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [181.567497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [181.567597] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.567697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.567797] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.567799] xe 0000:03:00.0: colorspace: RGB
<7> [181.567800] xe 0000:03:00.0: scan mode: Underscan
<7> [181.567801] xe 0000:03:00.0: colorimetry: No Data
<7> [181.567803] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.567804] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.567805] xe 0000:03:00.0: itc: No Data
<7> [181.567806] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.567808] xe 0000:03:00.0: quantization range: Limited
<7> [181.567809] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.567810] xe 0000:03:00.0: video code: 16
<7> [181.567811] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.567813] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.567814] xe 0000:03:00.0: pixel repeat: 0
<7> [181.567815] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.567817] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [181.567918] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.568026] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.568127] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.568129] xe 0000:03:00.0: vendor: Intel
<7> [181.568130] xe 0000:03:00.0: product: Discrete gfx
<7> [181.568132] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.568133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [181.568235] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.568336] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.568437] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.568439] xe 0000:03:00.0: empty frame
<7> [181.568440] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [181.568571] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [181.568663] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [181.568773] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [181.568860] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [181.568947] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271: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> [181.569066] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [181.569154] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [181.569239] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [181.569334] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe B] data rate 594000 num active planes 1
<7> [181.569444] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [181.569554] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [181.569661] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [181.569768] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [181.569888] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [181.570019] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe B] allocated TC PLL 3
<7> [181.570127] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe B] reserving TC PLL 3
<7> [181.570233] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: yes [modeset]
<7> [181.570342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [181.570447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [181.570554] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [181.570658] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.570762] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [181.570865] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [181.570973] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [181.571076] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [181.571179] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.571181] xe 0000:03:00.0: colorspace: RGB
<7> [181.571182] xe 0000:03:00.0: scan mode: Underscan
<7> [181.571184] xe 0000:03:00.0: colorimetry: No Data
<7> [181.571185] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.571186] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.571187] xe 0000:03:00.0: itc: No Data
<7> [181.571189] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.571190] xe 0000:03:00.0: quantization range: Limited
<7> [181.571191] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.571192] xe 0000:03:00.0: video code: 16
<7> [181.571193] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.571195] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.571196] xe 0000:03:00.0: pixel repeat: 0
<7> [181.571197] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.571199] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.571200] xe 0000:03:00.0: vendor: Intel
<7> [181.571201] xe 0000:03:00.0: product: Discrete gfx
<7> [181.571203] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.571204] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.571206] xe 0000:03:00.0: empty frame
<7> [181.571207] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [181.571309] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [181.571412] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [181.571521] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [181.571625] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [181.571729] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [181.571832] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [181.571934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [181.572045] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.572146] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.572249] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.572351] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [181.572452] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.572555] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [181.572660] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [181.572764] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [181.572867] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [181.572975] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.573076] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [181.573178] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [181.573280] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.573380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [181.573480] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [181.573581] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.573682] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [181.573783] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [181.573884] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [181.573990] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [181.574090] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [181.574189] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [181.574289] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [181.574389] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [181.574491] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [181.574595] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [181.574697] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [181.574799] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.574901] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x40000000 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.575009] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.575110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.575210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0db0 0x0000 0x0000
<7> [181.575311] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0db0 0x0000
<7> [181.575411] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0db0
<7> [181.575511] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0100 0x0100 0x0100
<7> [181.575611] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.575715] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.575819] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.575922] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.576030] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [181.576130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [181.576232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.576331] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [181.576432] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [181.576532] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [181.576633] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [181.576738] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [181.576841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [181.577471] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [181.577592] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [181.577931] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [181.578269] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.578370] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.578468] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.578562] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.578655] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.578748] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.578841] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.578932] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.578964] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [181.579035] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.579129] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.579224] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.579317] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.579410] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.579501] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.579592] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.579683] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.579988] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [181.580162] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [181.580452] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [181.580568] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:387:pipe B]
<7> [181.580673] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [181.583260] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [181.602081] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:387:pipe B], 36 bytes ELD
<7> [181.618255] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [181.618501] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [181.618881] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [181.619362] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [181.623659] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [181.623709] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [181.623862] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:151:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 min link_bpp 18.0000 max link_bpp 36.0000
<7> [181.624151] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [181.624404] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [181.624645] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:151:pipe A]
<7> [181.624873] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [181.625116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [181.625334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [181.625544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [181.625748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [181.625942] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [181.626163] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [181.626356] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.626550] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [181.626739] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.626922] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.627121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.627296] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.627472] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.627639] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.627797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.627967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.628120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.628273] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.628426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.628575] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [181.628722] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.628867] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.629016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.629154] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.629290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.629427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.629560] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.629690] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.629816] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.629938] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.630081] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [181.630201] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [181.630320] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [181.630293] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.630440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<6> [181.630614] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [181.630561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [181.630673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [181.630784] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [181.630893] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [181.631019] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [181.631162] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? yes->no
<7> [181.631258] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [181.631357] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0xc, ddb (0 - 4096) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [181.631477] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [181.631566] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [181.631649] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [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> [181.631733] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [181.631815] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [181.631897] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [181.631992] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 4037) -> ( 0 - 1989), size 4037 -> 1989
<7> [181.632068] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> (1989 - 2048), size 59 -> 59
<7> [181.632154] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [181.632257] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [181.632358] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [181.632457] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [181.632560] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [181.632675] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [181.632786] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 2
<7> [181.632885] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 2
<7> [181.632994] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [181.633098] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [181.633199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [181.633297] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [181.633394] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.633489] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [181.633586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [181.633685] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [181.633782] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [181.633877] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [181.634005] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [181.634106] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [181.634204] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [181.634300] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [181.634395] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [181.634516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [181.634631] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [181.634725] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [181.634820] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [181.634913] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 42 vsync start: 41, vsync end: 36
<7> [181.635019] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1083, vmax vblank: 1083, vmin vtotal: 1125, vmax vtotal: 1125
<7> [181.635113] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [181.635206] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.635301] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.635396] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.635492] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [181.635586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.635681] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [181.635776] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [181.635869] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [181.635968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [181.636066] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.636164] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [181.636261] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [181.636356] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.636452] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [181.636544] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [181.636639] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.636732] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [181.636825] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [181.636920] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [181.637023] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [181.637117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [181.637210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [181.637303] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [181.637393] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [181.637485] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [181.637578] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [181.637671] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [181.637763] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [181.637859] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x40000000 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.637964] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.638060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.638156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0db0 0x0000 0x0000
<7> [181.638254] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0db0 0x0000
<7> [181.638352] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0db0
<7> [181.638448] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0100 0x0100 0x0100
<7> [181.638542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.638636] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638727] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638821] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638915] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [181.639021] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [181.639123] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.639221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [181.639326] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [181.639425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [181.639522] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [181.639618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [181.639713] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [181.640571] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [181.640699] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa0000000)
<7> [181.640812] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.640907] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.641009] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.641100] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.641190] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.641280] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.641372] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.641462] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.641551] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.641639] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.641726] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.641812] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.641902] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.641997] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.642084] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.642170] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.651612] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.651945] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: B)
<7> [181.668537] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [181.668660] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [181.671172] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [181.675715] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [181.682999] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [181.684374] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [181.685041] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [181.686899] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [181.687787] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [181.694025] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [181.694585] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [181.701904] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [181.702525] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [181.704137] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [181.722067] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:151:pipe A], 40 bytes ELD
<7> [181.736731] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.738795] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [181.739704] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [181.811069] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [183.755035] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [183.774126] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [185.613945] [IGT] kms_flip: finished subtest AC-DP2-HDMI-A3, FAIL
Created at 2026-04-16 23:42:16