Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@d-hdmi-a3

Result: Fail

i915_display_info16 igt_runner16 results16.json results16-xe-load.json guc_logs16.tar i915_display_info_post_exec16 boot16 dmesg16

DetailValue
Duration 3.48 seconds
Hostname 
shard-bmg-10
Igt-Version 
IGT-Version: 2.3-g4ae192285 (x86_64) (Linux: 7.0.0-rc2-lgci-xe-xe-4674-a48305e6a2e6a1ed9-debug+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A3
  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:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A3: FAIL (3.481s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (31.398s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2351 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A3
(kms_flip:3775) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3775) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3775) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.801392
last_received_ts = 156.801041
last_seq = 577
current_ts = 156.818069
current_received_ts = 156.817688
current_seq = 578
count = 39
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.818069
last_received_ts = 156.817688
last_seq = 578
current_ts = 156.834747
current_received_ts = 156.834854
current_seq = 579
count = 40
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 38us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.834747
last_received_ts = 156.834854
last_seq = 579
current_ts = 156.851395
current_received_ts = 156.851089
current_seq = 580
count = 41
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 63us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.851395
last_received_ts = 156.851089
last_seq = 580
current_ts = 156.868073
current_received_ts = 156.867706
current_seq = 581
count = 42
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.868073
last_received_ts = 156.867706
last_seq = 581
current_ts = 156.884750
current_received_ts = 156.884445
current_seq = 582
count = 43
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.884750
last_received_ts = 156.884445
last_seq = 582
current_ts = 156.901398
current_received_ts = 156.900986
current_seq = 583
count = 44
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.901398
last_received_ts = 156.900986
last_seq = 583
current_ts = 156.918076
current_received_ts = 156.917770
current_seq = 584
count = 45
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.918076
last_received_ts = 156.917770
last_seq = 584
current_ts = 156.934738
current_received_ts = 156.934433
current_seq = 585
count = 46
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 43us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.934738
last_received_ts = 156.934433
last_seq = 585
current_ts = 156.951416
current_received_ts = 156.951065
current_seq = 586
count = 47
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 69us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.951416
last_received_ts = 156.951065
last_seq = 586
current_ts = 156.968079
current_received_ts = 156.967728
current_seq = 587
count = 48
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 34us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.968079
last_received_ts = 156.967728
last_seq = 587
current_ts = 156.984741
current_received_ts = 156.984375
current_seq = 588
count = 49
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 156.984741
last_received_ts = 156.984375
last_seq = 588
current_ts = 157.001404
current_received_ts = 157.000961
current_seq = 589
count = 50
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 37us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.001404
last_received_ts = 157.000961
last_seq = 589
current_ts = 157.018082
current_received_ts = 157.017700
current_seq = 590
count = 51
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.018082
last_received_ts = 157.017700
last_seq = 590
current_ts = 157.034744
current_received_ts = 157.034363
current_seq = 591
count = 52
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.034744
last_received_ts = 157.034363
last_seq = 591
current_ts = 157.051407
current_received_ts = 157.051056
current_seq = 592
count = 53
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.051407
last_received_ts = 157.051056
last_seq = 592
current_ts = 157.068085
current_received_ts = 157.067703
current_seq = 593
count = 54
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.068085
last_received_ts = 157.067703
last_seq = 593
current_ts = 157.084747
current_received_ts = 157.084381
current_seq = 594
count = 55
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.084747
last_received_ts = 157.084381
last_seq = 594
current_ts = 157.101410
current_received_ts = 157.101059
current_seq = 595
count = 56
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.101410
last_received_ts = 157.101059
last_seq = 595
current_ts = 157.118088
current_received_ts = 157.117706
current_seq = 596
count = 57
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.118088
last_received_ts = 157.117706
last_seq = 596
current_ts = 157.134750
current_received_ts = 157.134369
current_seq = 597
count = 58
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.134750
last_received_ts = 157.134369
last_seq = 597
current_ts = 157.151413
current_received_ts = 157.151062
current_seq = 598
count = 59
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.151413
last_received_ts = 157.151062
last_seq = 598
current_ts = 157.168091
current_received_ts = 157.167709
current_seq = 599
count = 60
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.168091
last_received_ts = 157.167709
last_seq = 599
current_ts = 157.184753
current_received_ts = 157.184372
current_seq = 600
count = 61
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.184753
last_received_ts = 157.184372
last_seq = 600
current_ts = 157.201416
current_received_ts = 157.201065
current_seq = 601
count = 62
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 24us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.201416
last_received_ts = 157.201065
last_seq = 601
current_ts = 157.218079
current_received_ts = 157.217712
current_seq = 602
count = 63
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.218079
last_received_ts = 157.217712
last_seq = 602
current_ts = 157.234756
current_received_ts = 157.234375
current_seq = 603
count = 64
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.234756
last_received_ts = 157.234375
last_seq = 603
current_ts = 157.251419
current_received_ts = 157.250977
current_seq = 604
count = 65
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 57us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.251419
last_received_ts = 157.250977
last_seq = 604
current_ts = 157.268082
current_received_ts = 157.267715
current_seq = 605
count = 66
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.268082
last_received_ts = 157.267715
last_seq = 605
current_ts = 157.284760
current_received_ts = 157.284393
current_seq = 606
count = 67
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 28us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.284760
last_received_ts = 157.284393
last_seq = 606
current_ts = 157.301422
current_received_ts = 157.300980
current_seq = 607
count = 68
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 39us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.301422
last_received_ts = 157.300980
last_seq = 607
current_ts = 157.318085
current_received_ts = 157.317642
current_seq = 608
count = 69
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.318085
last_received_ts = 157.317642
last_seq = 608
current_ts = 157.334763
current_received_ts = 157.334320
current_seq = 609
count = 70
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.334763
last_received_ts = 157.334320
last_seq = 609
current_ts = 157.351425
current_received_ts = 157.351059
current_seq = 610
count = 71
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.351425
last_received_ts = 157.351059
last_seq = 610
current_ts = 157.368103
current_received_ts = 157.367722
current_seq = 611
count = 72
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.368103
last_received_ts = 157.367722
last_seq = 611
current_ts = 157.384766
current_received_ts = 157.384399
current_seq = 612
count = 73
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.384766
last_received_ts = 157.384399
last_seq = 612
current_ts = 157.401428
current_received_ts = 157.401077
current_seq = 613
count = 74
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.401428
last_received_ts = 157.401077
last_seq = 613
current_ts = 157.418091
current_received_ts = 157.417725
current_seq = 614
count = 75
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.418091
last_received_ts = 157.417725
last_seq = 614
current_ts = 157.434769
current_received_ts = 157.434387
current_seq = 615
count = 76
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.434769
last_received_ts = 157.434387
last_seq = 615
current_ts = 157.451431
current_received_ts = 157.451080
current_seq = 616
count = 77
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.451431
last_received_ts = 157.451080
last_seq = 616
current_ts = 157.468109
current_received_ts = 157.467728
current_seq = 617
count = 78
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.468109
last_received_ts = 157.467728
last_seq = 617
current_ts = 157.484772
current_received_ts = 157.484406
current_seq = 618
count = 79
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.484772
last_received_ts = 157.484406
last_seq = 618
current_ts = 157.501434
current_received_ts = 157.501083
current_seq = 619
count = 80
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.501434
last_received_ts = 157.501083
last_seq = 619
current_ts = 157.518097
current_received_ts = 157.517731
current_seq = 620
count = 81
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.518097
last_received_ts = 157.517731
last_seq = 620
current_ts = 157.534775
current_received_ts = 157.534286
current_seq = 621
count = 82
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.534775
last_received_ts = 157.534286
last_seq = 621
current_ts = 157.551437
current_received_ts = 157.550949
current_seq = 622
count = 83
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.551437
last_received_ts = 157.550949
last_seq = 622
current_ts = 157.568100
current_received_ts = 157.567734
current_seq = 623
count = 84
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.568100
last_received_ts = 157.567734
last_seq = 623
current_ts = 157.584778
current_received_ts = 157.584396
current_seq = 624
count = 85
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.584778
last_received_ts = 157.584396
last_seq = 624
current_ts = 157.601440
current_received_ts = 157.600952
current_seq = 625
count = 86
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.601440
last_received_ts = 157.600952
last_seq = 625
current_ts = 157.618103
current_received_ts = 157.617737
current_seq = 626
count = 87
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.618103
last_received_ts = 157.617737
last_seq = 626
current_ts = 157.634781
current_received_ts = 157.634293
current_seq = 627
count = 88
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.634781
last_received_ts = 157.634293
last_seq = 627
current_ts = 157.651443
current_received_ts = 157.651093
current_seq = 628
count = 89
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.651443
last_received_ts = 157.651093
last_seq = 628
current_ts = 157.668106
current_received_ts = 157.667786
current_seq = 629
count = 90
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 40us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.668106
last_received_ts = 157.667786
last_seq = 629
current_ts = 157.684784
current_received_ts = 157.684540
current_seq = 630
count = 91
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 39us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.684784
last_received_ts = 157.684540
last_seq = 630
current_ts = 157.701447
current_received_ts = 157.701080
current_seq = 631
count = 92
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 46us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.701447
last_received_ts = 157.701080
last_seq = 631
current_ts = 157.718124
current_received_ts = 157.717834
current_seq = 632
count = 93
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.718124
last_received_ts = 157.717834
last_seq = 632
current_ts = 157.734787
current_received_ts = 157.734360
current_seq = 633
count = 94
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.734787
last_received_ts = 157.734360
last_seq = 633
current_ts = 157.751450
current_received_ts = 157.751038
current_seq = 634
count = 95
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 24us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.751450
last_received_ts = 157.751038
last_seq = 634
current_ts = 157.768112
current_received_ts = 157.767838
current_seq = 635
count = 96
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 24us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.768112
last_received_ts = 157.767838
last_seq = 635
current_ts = 157.784790
current_received_ts = 157.784409
current_seq = 636
count = 97
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.784790
last_received_ts = 157.784409
last_seq = 636
current_ts = 157.801453
current_received_ts = 157.801041
current_seq = 637
count = 98
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.801453
last_received_ts = 157.801041
last_seq = 637
current_ts = 157.818130
current_received_ts = 157.817825
current_seq = 638
count = 99
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.818130
last_received_ts = 157.817825
last_seq = 638
current_ts = 157.834793
current_received_ts = 157.834412
current_seq = 639
count = 100
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.834793
last_received_ts = 157.834412
last_seq = 639
current_ts = 157.851471
current_received_ts = 157.851166
current_seq = 640
count = 101
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.851471
last_received_ts = 157.851166
last_seq = 640
current_ts = 157.868134
current_received_ts = 157.867813
current_seq = 641
count = 102
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 24us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.868134
last_received_ts = 157.867813
last_seq = 641
current_ts = 157.884796
current_received_ts = 157.884460
current_seq = 642
count = 103
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 41us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.884796
last_received_ts = 157.884460
last_seq = 642
current_ts = 157.901459
current_received_ts = 157.901154
current_seq = 643
count = 104
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 33us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.901459
last_received_ts = 157.901154
last_seq = 643
current_ts = 157.918137
current_received_ts = 157.917770
current_seq = 644
count = 105
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 50us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.918137
last_received_ts = 157.917770
last_seq = 644
current_ts = 157.934799
current_received_ts = 157.934311
current_seq = 645
count = 106
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.934799
last_received_ts = 157.934311
last_seq = 645
current_ts = 157.951462
current_received_ts = 157.951080
current_seq = 646
count = 107
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.951462
last_received_ts = 157.951080
last_seq = 646
current_ts = 157.968124
current_received_ts = 157.967758
current_seq = 647
count = 108
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.968124
last_received_ts = 157.967758
last_seq = 647
current_ts = 157.984787
current_received_ts = 157.984558
current_seq = 648
count = 109
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 39us
(kms_flip:3775) DEBUG: name = flip
last_ts = 157.984787
last_received_ts = 157.984558
last_seq = 648
current_ts = 158.001465
current_received_ts = 158.001114
current_seq = 649
count = 110
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 38us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.001465
last_received_ts = 158.001114
last_seq = 649
current_ts = 158.018143
current_received_ts = 158.017853
current_seq = 650
count = 111
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.018143
last_received_ts = 158.017853
last_seq = 650
current_ts = 158.034805
current_received_ts = 158.034515
current_seq = 651
count = 112
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.034805
last_received_ts = 158.034515
last_seq = 651
current_ts = 158.051468
current_received_ts = 158.051178
current_seq = 652
count = 113
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.051468
last_received_ts = 158.051178
last_seq = 652
current_ts = 158.068146
current_received_ts = 158.067841
current_seq = 653
count = 114
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.068146
last_received_ts = 158.067841
last_seq = 653
current_ts = 158.084808
current_received_ts = 158.084518
current_seq = 654
count = 115
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.084808
last_received_ts = 158.084518
last_seq = 654
current_ts = 158.101471
current_received_ts = 158.101044
current_seq = 655
count = 116
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.101471
last_received_ts = 158.101044
last_seq = 655
current_ts = 158.118134
current_received_ts = 158.117722
current_seq = 656
count = 117
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.118134
last_received_ts = 158.117722
last_seq = 656
current_ts = 158.134811
current_received_ts = 158.134430
current_seq = 657
count = 118
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 43us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.134811
last_received_ts = 158.134430
last_seq = 657
current_ts = 158.151489
current_received_ts = 158.151093
current_seq = 658
count = 119
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.151489
last_received_ts = 158.151093
last_seq = 658
current_ts = 158.168137
current_received_ts = 158.167755
current_seq = 659
count = 120
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.168137
last_received_ts = 158.167755
last_seq = 659
current_ts = 158.184814
current_received_ts = 158.184509
current_seq = 660
count = 121
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.184814
last_received_ts = 158.184509
last_seq = 660
current_ts = 158.201492
current_received_ts = 158.201096
current_seq = 661
count = 122
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.201492
last_received_ts = 158.201096
last_seq = 661
current_ts = 158.218155
current_received_ts = 158.217850
current_seq = 662
count = 123
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.218155
last_received_ts = 158.217850
last_seq = 662
current_ts = 158.234802
current_received_ts = 158.234436
current_seq = 663
count = 124
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 43us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.234802
last_received_ts = 158.234436
last_seq = 663
current_ts = 158.251495
current_received_ts = 158.251099
current_seq = 664
count = 125
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.251495
last_received_ts = 158.251099
last_seq = 664
current_ts = 158.268158
current_received_ts = 158.267761
current_seq = 665
count = 126
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.268158
last_received_ts = 158.267761
last_seq = 665
current_ts = 158.284821
current_received_ts = 158.284393
current_seq = 666
count = 127
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.284821
last_received_ts = 158.284393
last_seq = 666
current_ts = 158.301498
current_received_ts = 158.301178
current_seq = 667
count = 128
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.301498
last_received_ts = 158.301178
last_seq = 667
current_ts = 158.318161
current_received_ts = 158.317856
current_seq = 668
count = 129
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.318161
last_received_ts = 158.317856
last_seq = 668
current_ts = 158.334808
current_received_ts = 158.334396
current_seq = 669
count = 130
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.334808
last_received_ts = 158.334396
last_seq = 669
current_ts = 158.351486
current_received_ts = 158.351059
current_seq = 670
count = 131
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.351486
last_received_ts = 158.351059
last_seq = 670
current_ts = 158.368164
current_received_ts = 158.367767
current_seq = 671
count = 132
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.368164
last_received_ts = 158.367767
last_seq = 671
current_ts = 158.384827
current_received_ts = 158.384521
current_seq = 672
count = 133
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.384827
last_received_ts = 158.384521
last_seq = 672
current_ts = 158.401505
current_received_ts = 158.401108
current_seq = 673
count = 134
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 45us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.401505
last_received_ts = 158.401108
last_seq = 673
current_ts = 158.418167
current_received_ts = 158.417847
current_seq = 674
count = 135
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.418167
last_received_ts = 158.417847
last_seq = 674
current_ts = 158.434830
current_received_ts = 158.434433
current_seq = 675
count = 136
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 44us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.434830
last_received_ts = 158.434433
last_seq = 675
current_ts = 158.451492
current_received_ts = 158.451172
current_seq = 676
count = 137
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.451492
last_received_ts = 158.451172
last_seq = 676
current_ts = 158.468170
current_received_ts = 158.467850
current_seq = 677
count = 138
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.468170
last_received_ts = 158.467850
last_seq = 677
current_ts = 158.484818
current_received_ts = 158.484406
current_seq = 678
count = 139
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.484818
last_received_ts = 158.484406
last_seq = 678
current_ts = 158.501495
current_received_ts = 158.501236
current_seq = 679
count = 140
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 36us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.501495
last_received_ts = 158.501236
last_seq = 679
current_ts = 158.518173
current_received_ts = 158.517838
current_seq = 680
count = 141
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 34us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.518173
last_received_ts = 158.517838
last_seq = 680
current_ts = 158.534821
current_received_ts = 158.534485
current_seq = 681
count = 142
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.534821
last_received_ts = 158.534485
last_seq = 681
current_ts = 158.551498
current_received_ts = 158.551147
current_seq = 682
count = 143
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.551498
last_received_ts = 158.551147
last_seq = 682
current_ts = 158.568161
current_received_ts = 158.567932
current_seq = 683
count = 144
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 65us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.568161
last_received_ts = 158.567932
last_seq = 683
current_ts = 158.584824
current_received_ts = 158.584503
current_seq = 684
count = 145
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 38us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.584824
last_received_ts = 158.584503
last_seq = 684
current_ts = 158.601501
current_received_ts = 158.601196
current_seq = 685
count = 146
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.601501
last_received_ts = 158.601196
last_seq = 685
current_ts = 158.618164
current_received_ts = 158.617859
current_seq = 686
count = 147
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 32us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.618164
last_received_ts = 158.617859
last_seq = 686
current_ts = 158.634827
current_received_ts = 158.634491
current_seq = 687
count = 148
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.634827
last_received_ts = 158.634491
last_seq = 687
current_ts = 158.651505
current_received_ts = 158.651154
current_seq = 688
count = 149
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.651505
last_received_ts = 158.651154
last_seq = 688
current_ts = 158.668167
current_received_ts = 158.667801
current_seq = 689
count = 150
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 45us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.668167
last_received_ts = 158.667801
last_seq = 689
current_ts = 158.684830
current_received_ts = 158.684464
current_seq = 690
count = 151
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 25us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.684830
last_received_ts = 158.684464
last_seq = 690
current_ts = 158.701508
current_received_ts = 158.701263
current_seq = 691
count = 152
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 48us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.701508
last_received_ts = 158.701263
last_seq = 691
current_ts = 158.718170
current_received_ts = 158.717850
current_seq = 692
count = 153
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 46us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.718170
last_received_ts = 158.717850
last_seq = 692
current_ts = 158.734848
current_received_ts = 158.734558
current_seq = 693
count = 154
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 31us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.734848
last_received_ts = 158.734558
last_seq = 693
current_ts = 158.751511
current_received_ts = 158.751099
current_seq = 694
count = 155
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 27us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.751511
last_received_ts = 158.751099
last_seq = 694
current_ts = 158.768173
current_received_ts = 158.767868
current_seq = 695
count = 156
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 26us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.768173
last_received_ts = 158.767868
last_seq = 695
current_ts = 158.784851
current_received_ts = 158.784531
current_seq = 696
count = 157
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 245us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.784851
last_received_ts = 158.784531
last_seq = 696
current_ts = 158.801514
current_received_ts = 158.801208
current_seq = 697
count = 158
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 233us
(kms_flip:3775) DEBUG: name = flip
last_ts = 158.801514
last_received_ts = 158.801208
last_seq = 697
current_ts = 158.818176
current_received_ts = 158.817871
current_seq = 698
count = 159
seq_step = 1
(kms_flip:3775) DEBUG: Vblank took 681us
(kms_flip:3775) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3775) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3775) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3775) igt_core-INFO: Stack trace:
(kms_flip:3775) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3775) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:3775) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:3775) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:3775) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:3775) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3775) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3775) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A3: FAIL (3.481s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (31.398s)
(kms_flip:3775) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351:
(kms_flip:3775) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:3775) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:3775) igt_kms-CRITICAL: DPMS property not found on 538
Dmesg
<6> [156.354514] [IGT] kms_flip: starting dynamic subtest D-HDMI-A3
<7> [156.355406] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:556]
<7> [156.355688] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [156.401070] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [156.401273] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [156.401428] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [156.401515] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [156.401698] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [156.401855] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [156.402102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [156.402259] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [156.402414] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [156.402566] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [156.402718] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [156.402870] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [156.403029] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [156.403190] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [156.403343] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [156.403494] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [156.403646] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [156.403797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [156.403949] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [156.404126] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [156.404278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [156.404429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [156.404580] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [156.404730] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [156.404880] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [156.405037] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [156.405187] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [156.405338] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [156.405488] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [156.405638] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [156.405789] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [156.405941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [156.406100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [156.406256] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [156.406411] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [156.406570] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [156.406732] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [156.406897] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [156.407081] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [156.407250] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [156.407421] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [156.407598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [156.407782] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [156.407965] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [156.408179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in avi infoframe
<7> [156.408382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.408576] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [156.408579] xe 0000:03:00.0: colorspace: RGB
<7> [156.408582] xe 0000:03:00.0: scan mode: Underscan
<7> [156.408584] xe 0000:03:00.0: colorimetry: No Data
<7> [156.408587] xe 0000:03:00.0: picture aspect: 16:9
<7> [156.408589] xe 0000:03:00.0: active aspect: Same as Picture
<7> [156.408591] xe 0000:03:00.0: itc: No Data
<7> [156.408593] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [156.408596] xe 0000:03:00.0: quantization range: Default
<7> [156.408598] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [156.408600] xe 0000:03:00.0: video code: 16
<7> [156.408603] xe 0000:03:00.0: ycc quantization range: Full
<7> [156.408605] xe 0000:03:00.0: hdmi content type: Graphics
<7> [156.408607] xe 0000:03:00.0: pixel repeat: 0
<7> [156.408609] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [156.408612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.408815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in spd infoframe
<7> [156.409033] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.409247] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [156.409250] xe 0000:03:00.0: vendor: Intel
<7> [156.409252] xe 0000:03:00.0: product: Discrete gfx
<7> [156.409254] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [156.409257] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.409464] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hdmi infoframe
<7> [156.409672] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.409880] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [156.409882] xe 0000:03:00.0: empty frame
<7> [156.409884] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.410122] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] fastset requirement not met, forcing full modeset
<7> [156.410355] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:386:pipe C] releasing TC PLL 3
<7> [156.410591] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [156.410762] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:386:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [156.410974] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:380:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [156.411194] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:386:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [156.411427] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:386:pipe C] enable: no [modeset]
<7> [156.411626] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:270:plane 1C] fb: [NOFB], visible: no
<7> [156.411825] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:300:plane 2C] fb: [NOFB], visible: no
<7> [156.412028] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:330:plane 3C] fb: [NOFB], visible: no
<7> [156.412219] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:360:plane 4C] fb: [NOFB], visible: no
<7> [156.412404] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:370:plane 5C] fb: [NOFB], visible: no
<7> [156.412582] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:380:cursor C] fb: [NOFB], visible: no
<7> [156.413456] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Disable audio codec on [CRTC:386:pipe C]
<7> [156.432398] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [156.433115] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [156.454294] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x4, on? 1) for [CRTC:386:pipe C]
<7> [156.456256] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [156.456928] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [156.457875] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [156.458444] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [156.458973] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [156.459521] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [156.460057] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [156.460580] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [156.461118] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [156.461640] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [156.462186] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [156.462703] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [156.463183] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [156.463761] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [156.464222] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [156.464638] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [156.465056] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [156.465435] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [156.465795] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [156.466246] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [156.466711] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [156.467274] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [156.467751] 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> [156.468281] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:386:pipe C]
<7> [156.469021] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [156.469859] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [156.469901] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [156.470063] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [156.470316] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:504:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [156.470578] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:504:pipe D]
<7> [156.470828] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [156.471003] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [156.471111] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [156.471333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [156.471556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [156.471765] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [156.471971] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [156.472191] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [156.472382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [156.472569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [156.472749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [156.472927] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [156.473116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [156.473290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [156.473454] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [156.473618] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [156.473779] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [156.473936] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [156.474103] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [156.474261] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [156.474412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [156.474555] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [156.474695] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [156.474833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [156.474972] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [156.475171] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [156.475302] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [156.475432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [156.475559] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [156.475680] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [156.475803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [156.475924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [156.476054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [156.476168] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [156.476282] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [156.476393] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [156.476505] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in dpll_hw_state
<7> [156.476611] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.476713] 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> [156.476816] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [156.476921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [156.477032] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [156.477139] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x308c
<7> [156.477243] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x2110
<7> [156.477343] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0xcc9c
<7> [156.477439] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0xbfc1
<7> [156.477533] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x4b9a
<7> [156.477626] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x3f81
<7> [156.477718] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2000
<7> [156.477809] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0x0001
<7> [156.477901] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x5000
<7> [156.477997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [156.478088] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [156.478174] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [156.478260] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.478343] 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> [156.478428] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [156.478513] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [156.478597] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [156.478681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [156.478765] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [156.478849] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [156.478932] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [156.479029] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [156.479120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [156.479203] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [156.479286] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [156.479369] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [156.479452] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [156.479541] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [156.479635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [156.479732] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [156.479827] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [156.479928] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [156.480033] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [156.480129] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [156.480225] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [156.480319] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in avi infoframe
<7> [156.480413] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.480509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.480604] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [156.480605] xe 0000:03:00.0: colorspace: RGB
<7> [156.480606] xe 0000:03:00.0: scan mode: Underscan
<7> [156.480607] xe 0000:03:00.0: colorimetry: No Data
<7> [156.480608] xe 0000:03:00.0: picture aspect: 16:9
<7> [156.480610] xe 0000:03:00.0: active aspect: Same as Picture
<7> [156.480611] xe 0000:03:00.0: itc: No Data
<7> [156.480612] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [156.480613] xe 0000:03:00.0: quantization range: Default
<7> [156.480614] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [156.480615] xe 0000:03:00.0: video code: 16
<7> [156.480616] xe 0000:03:00.0: ycc quantization range: Full
<7> [156.480617] xe 0000:03:00.0: hdmi content type: Graphics
<7> [156.480618] xe 0000:03:00.0: pixel repeat: 0
<7> [156.480619] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [156.480621] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in spd infoframe
<7> [156.480716] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.480810] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.480902] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [156.480903] xe 0000:03:00.0: vendor: Intel
<7> [156.480904] xe 0000:03:00.0: product: Discrete gfx
<7> [156.480905] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [156.480907] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hdmi infoframe
<7> [156.481001] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [156.481091] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [156.481183] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [156.481185] xe 0000:03:00.0: empty frame
<7> [156.481186] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset
<7> [156.481298] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [156.481380] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:504:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [156.481480] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:388:plane 1D] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [156.481559] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:498:cursor D] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [156.481634] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [156.481707] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [156.481780] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [156.481852] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] 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> [156.481940] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:504:pipe D] data rate 594000 num active planes 1
<7> [156.482051] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [156.482150] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [156.482246] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [156.482353] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:504:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [156.482473] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:504:pipe D] allocated TC PLL 3
<7> [156.482570] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:504:pipe D] reserving TC PLL 3
<7> [156.482664] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:504:pipe D] enable: yes [modeset]
<7> [156.482766] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [156.482865] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [156.482962] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [156.483070] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [156.483164] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [156.483257] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [156.483349] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [156.483442] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [156.483533] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [156.483535] xe 0000:03:00.0: colorspace: RGB
<7> [156.483536] xe 0000:03:00.0: scan mode: Underscan
<7> [156.483537] xe 0000:03:00.0: colorimetry: No Data
<7> [156.483538] xe 0000:03:00.0: picture aspect: 16:9
<7> [156.483539] xe 0000:03:00.0: active aspect: Same as Picture
<7> [156.483540] xe 0000:03:00.0: itc: No Data
<7> [156.483541] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [156.483542] xe 0000:03:00.0: quantization range: Default
<7> [156.483543] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [156.483544] xe 0000:03:00.0: video code: 16
<7> [156.483546] xe 0000:03:00.0: ycc quantization range: Full
<7> [156.483547] xe 0000:03:00.0: hdmi content type: Graphics
<7> [156.483548] xe 0000:03:00.0: pixel repeat: 0
<7> [156.483549] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [156.483550] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [156.483551] xe 0000:03:00.0: vendor: Intel
<7> [156.483553] xe 0000:03:00.0: product: Discrete gfx
<7> [156.483554] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [156.483555] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [156.483556] xe 0000:03:00.0: empty frame
<7> [156.483557] 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> [156.483651] 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> [156.483742] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [156.483833] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [156.483924] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [156.484023] 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> [156.484115] 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> [156.484206] 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> [156.484299] 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> [156.484396] 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> [156.484491] 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> [156.484584] 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> [156.484678] 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> [156.484770] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [156.484861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [156.484952] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [156.485049] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [156.485141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [156.485232] 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> [156.485322] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [156.485413] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [156.485505] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [156.485596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [156.485688] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [156.485781] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [156.485872] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [156.485966] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [156.486068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [156.486160] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [156.486251] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [156.486342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [156.486433] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [156.486524] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [156.486615] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [156.486707] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [156.486804] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [156.486900] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [156.487005] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487012] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [156.487098] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487191] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487283] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [156.487374] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [156.487397] xe 0000:03:00.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [156.487464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487557] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487550] xe 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [156.487648] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.487740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [156.487831] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [156.487922] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:388:plane 1D] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [156.487922] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [156.488026] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [156.488119] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [156.488211] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:418:plane 2D] fb: [NOFB], visible: no
<7> [156.488302] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:448:plane 3D] fb: [NOFB], visible: no
<7> [156.488392] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:478:plane 4D] fb: [NOFB], visible: no
<7> [156.488483] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:488:plane 5D] fb: [NOFB], visible: no
<7> [156.488572] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:498:cursor D] fb: [NOFB], visible: no
<7> [156.489186] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [156.489288] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [156.489524] xe 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [156.489651] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [156.489785] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [156.489927] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [156.490046] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [156.490138] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [156.490228] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [156.490316] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [156.490401] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [156.490489] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [156.490577] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [156.490661] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [156.490744] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [156.490826] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [156.490910] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [156.491001] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [156.491083] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [156.491164] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [156.491246] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [156.491327] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [156.491422] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [156.491588] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [156.491688] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:504:pipe D]
<7> [156.491781] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [156.494284] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [156.512626] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Enable audio codec on [CRTC:504:pipe D], 36 bytes ELD
<7> [156.525437] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [156.529171] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [156.529306] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [156.529826] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [156.530251] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:504:pipe D]
<6> [159.843704] [IGT] kms_flip: finished subtest D-HDMI-A3, FAIL
<6> [159.849493] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [159.856435] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:557] from all active usage due to RMFB ioctl
<7> [159.856466] xe 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:388:plane 1D] because [FB:557] is removed
<7> [159.856498] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:388:plane 1D] ddb ( 0 - 1989) -> ( 0 - 0), size 1989 -> 0
<7> [159.856598] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [159.856673] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] lines 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [159.856743] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] blocks 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [159.856812] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:388:plane 1D] min_ddb 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [159.856894] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:504:pipe D] data rate 0 num active planes 0
<7> [159.857012] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [159.857112] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [159.857245] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [159.865268] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:525:DDI TC2/PHY G][CRTC:150: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> [159.865895] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [159.867578] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [159.868109] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [159.868504] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [159.868941] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:525:DDI TC2/PHY G] [CRTC:150:pipe A]
<7> [159.869359] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:268:pipe B]
<7> [159.869729] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [159.870100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [159.870423] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [159.870732] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [159.871047] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150: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> [159.871336] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [159.871600] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [159.871874] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [159.872230] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [159.872467] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [159.872697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [159.872917] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [159.873154] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [159.873360] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [159.873565] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [159.873758] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [159.873947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [159.874153] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [159.874334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [159.874505] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [159.874681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [159.874850] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [159.875025] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [159.875180] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [159.875331] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [159.875480] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [159.875627] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [159.875774] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [159.875914] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [159.876083] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [159.876223] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [159.876351] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [159.876479] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [159.876599] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [159.876717] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [159.876836] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in dpll_hw_state
<7> [159.876959] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.877077] 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> [159.877192] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [159.877298] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [159.877406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [159.877507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [159.877608] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [159.877708] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [159.877807] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [159.877906] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [159.878014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [159.878114] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [159.878213] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [159.878311] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [159.878410] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [159.878509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [159.878607] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [159.878706] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.878805] 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> [159.878904] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [159.879011] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [159.879111] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [159.879211] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x308c
<7> [159.879311] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x2110
<7> [159.879412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0xcc9c
<7> [159.879510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0xbfc1
<7> [159.879610] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x4b9a
<7> [159.879709] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x3f81
<7> [159.879808] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2000
<7> [159.879905] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0x0001
<7> [159.880016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x5000
<7> [159.880116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [159.880215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [159.880315] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [159.880415] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [159.880514] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [159.880613] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [159.880712] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [159.880812] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [159.880912] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [159.881020] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [159.881121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [159.881222] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [159.881323] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [159.881423] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [159.881522] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [159.881623] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [159.881728] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [159.881833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [159.881935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [159.882048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [159.882147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [159.882246] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [159.882344] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [159.882442] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [159.882541] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [159.882640] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [159.882738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [159.882837] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [159.882936] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [159.883046] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [159.883145] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [159.883243] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [159.883342] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [159.883440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [159.883539] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [159.883637] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [159.883736] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [159.883839] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [159.883941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [159.884054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [159.884155] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [159.884254] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [159.884353] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [159.884454] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [159.884557] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [159.884661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [159.884763] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [159.884865] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [159.884971] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [159.885073] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [159.885181] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.885285] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.885386] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [159.885388] xe 0000:03:00.0: colorspace: RGB
<7> [159.885390] xe 0000:03:00.0: scan mode: Underscan
<7> [159.885391] xe 0000:03:00.0: colorimetry: No Data
<7> [159.885392] xe 0000:03:00.0: picture aspect: 16:9
<7> [159.885394] xe 0000:03:00.0: active aspect: Same as Picture
<7> [159.885395] xe 0000:03:00.0: itc: No Data
<7> [159.885396] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [159.885398] xe 0000:03:00.0: quantization range: Default
<7> [159.885399] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [159.885400] xe 0000:03:00.0: video code: 16
<7> [159.885402] xe 0000:03:00.0: ycc quantization range: Full
<7> [159.885403] xe 0000:03:00.0: hdmi content type: Graphics
<7> [159.885404] xe 0000:03:00.0: pixel repeat: 0
<7> [159.885405] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [159.885407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [159.885511] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.885612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.885713] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [159.885714] xe 0000:03:00.0: vendor: Intel
<7> [159.885716] xe 0000:03:00.0: product: Discrete gfx
<7> [159.885717] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [159.885719] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [159.885819] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.885919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.886027] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [159.886029] xe 0000:03:00.0: empty frame
<7> [159.886030] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [159.886130] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [159.886229] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [159.886327] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [159.886427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [159.886532] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [159.886636] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [159.886738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [159.886840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [159.886941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [159.887062] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [159.887163] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [159.887264] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [159.887364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [159.887464] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [159.887562] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [159.887661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [159.887761] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [159.887859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [159.887962] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [159.888060] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [159.888158] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [159.888257] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [159.888357] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [159.888457] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [159.888556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [159.888656] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [159.888753] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [159.888859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [159.888966] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [159.889070] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [159.889173] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [159.889278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [159.889382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [159.889484] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [159.889585] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [159.889686] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [159.889787] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [159.889886] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [159.889991] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [159.890090] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [159.890189] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [159.890289] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in avi infoframe
<7> [159.890389] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.890489] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [159.890491] xe 0000:03:00.0: colorspace: RGB
<7> [159.890492] xe 0000:03:00.0: scan mode: Underscan
<7> [159.890493] xe 0000:03:00.0: colorimetry: No Data
<7> [159.890494] xe 0000:03:00.0: picture aspect: 16:9
<7> [159.890496] xe 0000:03:00.0: active aspect: Same as Picture
<7> [159.890497] xe 0000:03:00.0: itc: No Data
<7> [159.890498] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [159.890500] xe 0000:03:00.0: quantization range: Default
<7> [159.890501] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [159.890502] xe 0000:03:00.0: video code: 16
<7> [159.890503] xe 0000:03:00.0: ycc quantization range: Full
<7> [159.890505] xe 0000:03:00.0: hdmi content type: Graphics
<7> [159.890506] xe 0000:03:00.0: pixel repeat: 0
<7> [159.890507] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [159.890509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.890609] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in spd infoframe
<7> [159.890708] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.890807] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [159.890809] xe 0000:03:00.0: vendor: Intel
<7> [159.890810] xe 0000:03:00.0: product: Discrete gfx
<7> [159.890811] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [159.890813] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.890913] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:504:pipe D] fastset requirement not met in hdmi infoframe
<7> [159.891020] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [159.891119] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [159.891120] xe 0000:03:00.0: empty frame
<7> [159.891121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [159.891220] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset
<7> [159.891320] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:504:pipe D] releasing TC PLL 3
<7> [159.891454] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [159.891545] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x3
<7> [159.891632] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x3
<7> [159.891714] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:504:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x3
<7> [159.891799] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [159.891878] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [159.891961] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [159.892038] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [159.892115] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 145, 145, 273, 273, 273, 273, 30, 241, 255
<7> [159.892195] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 161, 161, 302, 302, 0, 0, 31, 267, 267
<7> [159.892276] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [159.892355] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [159.892431] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [159.892508] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [159.892587] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 145, 145, 273, 273, 273, 273, 30, 241, 255
<7> [159.892665] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 161, 161, 302, 302, 0, 0, 31, 267, 267
<7> [159.892743] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:498:cursor D] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [159.892833] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 594000 num active planes 1
<7> [159.892941] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 594000 num active planes 1
<7> [159.893061] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [159.893164] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [159.893264] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [159.893375] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [159.893476] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [159.893578] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:504:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [159.893691] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [159.893808] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated TC PLL 2
<7> [159.893914] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving TC PLL 2
<7> [159.894025] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:268:pipe B] allocated TC PLL 3
<7> [159.894125] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:268:pipe B] reserving TC PLL 3
<7> [159.894227] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [159.894336] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [159.894440] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [159.894543] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [159.894644] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [159.894745] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [159.894844] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [159.894945] 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> [159.895068] 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> [159.895170] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [159.895270] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [159.895370] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [159.895468] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [159.895565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [159.895664] 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> [159.895763] 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> [159.895861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [159.895962] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [159.896060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [159.896159] 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> [159.896259] 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> [159.896358] 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> [159.896456] 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> [159.896554] 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> [159.896652] 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> [159.896751] 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> [159.896851] 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> [159.896950] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [159.897060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [159.897158] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [159.897255] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [159.897353] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [159.897451] 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> [159.897549] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [159.897646] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [159.897743] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [159.897840] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [159.897937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [159.898045] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [159.898142] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [159.898240] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [159.898337] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [159.898437] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [159.898537] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [159.898637] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [159.898737] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [159.898836] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [159.898935] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [159.899047] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [159.899146] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [159.899244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [159.899342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.899439] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.899537] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.899635] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [159.899732] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [159.899830] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.899937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.900053] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.900155] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [159.900256] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [159.900358] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:554] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [159.900461] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [159.900560] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [159.900660] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [159.900760] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [159.900860] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [159.900966] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [159.901067] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [159.901167] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: yes [modeset]
<7> [159.901264] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [159.901362] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [159.901459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [159.901558] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [159.901656] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [159.901754] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [159.901851] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [159.901949] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [159.902058] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [159.902059] xe 0000:03:00.0: colorspace: RGB
<7> [159.902061] xe 0000:03:00.0: scan mode: Underscan
<7> [159.902062] xe 0000:03:00.0: colorimetry: No Data
<7> [159.902064] xe 0000:03:00.0: picture aspect: 16:9
<7> [159.902065] xe 0000:03:00.0: active aspect: Same as Picture
<7> [159.902067] xe 0000:03:00.0: itc: No Data
<7> [159.902068] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [159.902069] xe 0000:03:00.0: quantization range: Default
<7> [159.902070] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [159.902072] xe 0000:03:00.0: video code: 16
<7> [159.902073] xe 0000:03:00.0: ycc quantization range: Full
<7> [159.902074] xe 0000:03:00.0: hdmi content type: Graphics
<7> [159.902076] xe 0000:03:00.0: pixel repeat: 0
<7> [159.902077] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [159.902078] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [159.902080] xe 0000:03:00.0: vendor: Intel
<7> [159.902081] xe 0000:03:00.0: product: Discrete gfx
<7> [159.902083] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [159.902085] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [159.902086] xe 0000:03:00.0: empty frame
<7> [159.902088] 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> [159.902184] 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> [159.902280] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [159.902377] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [159.902474] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [159.902569] 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> [159.902667] 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> [159.902764] 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> [159.902862] 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> [159.902962] 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> [159.903061] 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> [159.903158] 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> [159.903255] 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> [159.903352] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [159.903449] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [159.903547] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [159.903643] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [159.903740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [159.903837] 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> [159.903936] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [159.904047] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [159.904146] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [159.904244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [159.904342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [159.904438] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [159.904536] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [159.904633] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [159.904730] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [159.904832] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [159.904934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [159.905042] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [159.905142] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [159.905241] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [159.905341] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [159.905439] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [159.905537] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [159.905637] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [159.905740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.905841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.905942] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.906053] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [159.906153] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [159.906252] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.906349] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.906446] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [159.906542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [159.906640] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [159.906738] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [FB:554] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [159.906836] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [159.906933] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [159.907054] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [159.907155] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [159.907252] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [159.907348] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [159.907445] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [159.907542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:504:pipe D] enable: no [modeset]
<7> [159.907639] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:388:plane 1D] fb: [NOFB], visible: no
<7> [159.907737] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:418:plane 2D] fb: [NOFB], visible: no
<7> [159.907834] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:448:plane 3D] fb: [NOFB], visible: no
<7> [159.907931] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:478:plane 4D] fb: [NOFB], visible: no
<7> [159.908039] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:488:plane 5D] fb: [NOFB], visible: no
<7> [159.908135] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:498:cursor D] fb: [NOFB], visible: no
<7> [159.908841] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [159.909003] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x28000000)
<7> [159.909120] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [159.909364] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0x20000000)
<7> [159.909474] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Disable audio codec on [CRTC:504:pipe D]
<7> [159.929346] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [159.929728] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe D
<7> [159.950209] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x8, on? 1) for [CRTC:504:pipe D]
<7> [159.951820] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [159.952206] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [159.952306] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [159.952405] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [159.952498] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [159.952591] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [159.952682] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [159.952772] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [159.952863] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [159.952960] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [159.953052] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [159.953142] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [159.953234] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [159.953327] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [159.953419] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [159.953509] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [159.953601] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [159.953907] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [159.954092] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [159.954163] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [159.954205] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [159.954308] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [159.956848] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [159.960620] 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> [159.965068] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [159.966081] 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> [159.966528] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [159.967970] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [159.968542] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [159.974881] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [159.975178] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [159.981867] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [159.982352] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [159.983823] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [159.984382] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:268:pipe B]
<7> [159.984492] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [159.987117] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [160.005591] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Enable audio codec on [CRTC:150:pipe A], 40 bytes ELD
<7> [160.018387] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]
<7> [160.019056] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [160.019682] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [160.023744] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Enable audio codec on [CRTC:268:pipe B], 36 bytes ELD
<7> [160.038878] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [160.039445] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [160.040360] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [160.041159] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [160.045620] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [160.046196] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xa0000000)
<7> [160.046928] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:504:pipe D]
<7> [160.047758] xe 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [160.049328] [IGT] kms_flip: exiting, ret=98
<7> [160.085101] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
Created at 2026-03-07 14:36:00