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

Result: Fail

i915_display_info17 igt_runner17 results17.json results17-xe-load.json guc_logs17.tar i915_display_info_post_exec17 boot17 dmesg17

DetailValue
Duration 4.95 seconds
Hostname 
shard-bmg-5
Igt-Version 
IGT-Version: 2.3-g75d001e17 (x86_64) (Linux: 7.0.0-rc4-lgci-xe-xe-4742-146a21986f74225d0-debug+ x86_64)
Out 
Using IGT_SRANDOM=1773947464 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A3: FAIL (4.949s)
Err 
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
(kms_flip:9139) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:9139) CRITICAL: Failed assertion: end - start < 500
(kms_flip:9139) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:9139) DEBUG: Vblank took 29us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.299438
last_received_ts = 428.299103
last_seq = 16575
current_ts = 428.316101
current_received_ts = 428.315735
current_seq = 16576
count = 125
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.316101
last_received_ts = 428.315735
last_seq = 16576
current_ts = 428.332764
current_received_ts = 428.332397
current_seq = 16577
count = 126
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.332764
last_received_ts = 428.332397
last_seq = 16577
current_ts = 428.349426
current_received_ts = 428.349091
current_seq = 16578
count = 127
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 27us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.349426
last_received_ts = 428.349091
last_seq = 16578
current_ts = 428.366089
current_received_ts = 428.365723
current_seq = 16579
count = 128
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.366089
last_received_ts = 428.365723
last_seq = 16579
current_ts = 428.382751
current_received_ts = 428.382385
current_seq = 16580
count = 129
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.382751
last_received_ts = 428.382385
last_seq = 16580
current_ts = 428.399445
current_received_ts = 428.399109
current_seq = 16581
count = 130
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.399445
last_received_ts = 428.399109
last_seq = 16581
current_ts = 428.416107
current_received_ts = 428.415710
current_seq = 16582
count = 131
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.416107
last_received_ts = 428.415710
last_seq = 16582
current_ts = 428.432770
current_received_ts = 428.432373
current_seq = 16583
count = 132
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.432770
last_received_ts = 428.432373
last_seq = 16583
current_ts = 428.449432
current_received_ts = 428.449097
current_seq = 16584
count = 133
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.449432
last_received_ts = 428.449097
last_seq = 16584
current_ts = 428.466095
current_received_ts = 428.465729
current_seq = 16585
count = 134
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.466095
last_received_ts = 428.465729
last_seq = 16585
current_ts = 428.482758
current_received_ts = 428.482391
current_seq = 16586
count = 135
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.482758
last_received_ts = 428.482391
last_seq = 16586
current_ts = 428.499420
current_received_ts = 428.499084
current_seq = 16587
count = 136
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 29us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.499420
last_received_ts = 428.499084
last_seq = 16587
current_ts = 428.516083
current_received_ts = 428.515717
current_seq = 16588
count = 137
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.516083
last_received_ts = 428.515717
last_seq = 16588
current_ts = 428.532776
current_received_ts = 428.532379
current_seq = 16589
count = 138
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.532776
last_received_ts = 428.532379
last_seq = 16589
current_ts = 428.549438
current_received_ts = 428.549103
current_seq = 16590
count = 139
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.549438
last_received_ts = 428.549103
last_seq = 16590
current_ts = 428.566101
current_received_ts = 428.565735
current_seq = 16591
count = 140
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.566101
last_received_ts = 428.565735
last_seq = 16591
current_ts = 428.582764
current_received_ts = 428.582397
current_seq = 16592
count = 141
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.582764
last_received_ts = 428.582397
last_seq = 16592
current_ts = 428.599426
current_received_ts = 428.599091
current_seq = 16593
count = 142
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.599426
last_received_ts = 428.599091
last_seq = 16593
current_ts = 428.616089
current_received_ts = 428.615723
current_seq = 16594
count = 143
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.616089
last_received_ts = 428.615723
last_seq = 16594
current_ts = 428.632751
current_received_ts = 428.632385
current_seq = 16595
count = 144
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.632751
last_received_ts = 428.632385
last_seq = 16595
current_ts = 428.649414
current_received_ts = 428.649078
current_seq = 16596
count = 145
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 31us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.649414
last_received_ts = 428.649078
last_seq = 16596
current_ts = 428.666107
current_received_ts = 428.665710
current_seq = 16597
count = 146
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.666107
last_received_ts = 428.665710
last_seq = 16597
current_ts = 428.682770
current_received_ts = 428.682373
current_seq = 16598
count = 147
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.682770
last_received_ts = 428.682373
last_seq = 16598
current_ts = 428.699432
current_received_ts = 428.699066
current_seq = 16599
count = 148
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.699432
last_received_ts = 428.699066
last_seq = 16599
current_ts = 428.716095
current_received_ts = 428.715729
current_seq = 16600
count = 149
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.716095
last_received_ts = 428.715729
last_seq = 16600
current_ts = 428.732758
current_received_ts = 428.732391
current_seq = 16601
count = 150
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.732758
last_received_ts = 428.732391
last_seq = 16601
current_ts = 428.749420
current_received_ts = 428.749084
current_seq = 16602
count = 151
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 27us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.749420
last_received_ts = 428.749084
last_seq = 16602
current_ts = 428.766083
current_received_ts = 428.765717
current_seq = 16603
count = 152
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.766083
last_received_ts = 428.765717
last_seq = 16603
current_ts = 428.782745
current_received_ts = 428.782379
current_seq = 16604
count = 153
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.782745
last_received_ts = 428.782379
last_seq = 16604
current_ts = 428.799438
current_received_ts = 428.799103
current_seq = 16605
count = 154
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.799438
last_received_ts = 428.799103
last_seq = 16605
current_ts = 428.816101
current_received_ts = 428.815704
current_seq = 16606
count = 155
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.816101
last_received_ts = 428.815704
last_seq = 16606
current_ts = 428.832764
current_received_ts = 428.832397
current_seq = 16607
count = 156
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.832764
last_received_ts = 428.832397
last_seq = 16607
current_ts = 428.849426
current_received_ts = 428.849091
current_seq = 16608
count = 157
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 31us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.849426
last_received_ts = 428.849091
last_seq = 16608
current_ts = 428.866089
current_received_ts = 428.865723
current_seq = 16609
count = 158
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.866089
last_received_ts = 428.865723
last_seq = 16609
current_ts = 428.882751
current_received_ts = 428.882385
current_seq = 16610
count = 159
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.882751
last_received_ts = 428.882385
last_seq = 16610
current_ts = 428.899414
current_received_ts = 428.899109
current_seq = 16611
count = 160
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 28us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.899414
last_received_ts = 428.899109
last_seq = 16611
current_ts = 428.916107
current_received_ts = 428.915710
current_seq = 16612
count = 161
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.916107
last_received_ts = 428.915710
last_seq = 16612
current_ts = 428.932770
current_received_ts = 428.932373
current_seq = 16613
count = 162
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.932770
last_received_ts = 428.932373
last_seq = 16613
current_ts = 428.949432
current_received_ts = 428.949249
current_seq = 16614
count = 163
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 33us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.949432
last_received_ts = 428.949249
last_seq = 16614
current_ts = 428.966095
current_received_ts = 428.965729
current_seq = 16615
count = 164
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 37us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.966095
last_received_ts = 428.965729
last_seq = 16615
current_ts = 428.982758
current_received_ts = 428.982391
current_seq = 16616
count = 165
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.982758
last_received_ts = 428.982391
last_seq = 16616
current_ts = 428.999420
current_received_ts = 428.999115
current_seq = 16617
count = 166
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 428.999420
last_received_ts = 428.999115
last_seq = 16617
current_ts = 429.016083
current_received_ts = 429.015778
current_seq = 16618
count = 167
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.016083
last_received_ts = 429.015778
last_seq = 16618
current_ts = 429.032745
current_received_ts = 429.032440
current_seq = 16619
count = 168
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.032745
last_received_ts = 429.032440
last_seq = 16619
current_ts = 429.049408
current_received_ts = 429.049103
current_seq = 16620
count = 169
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.049408
last_received_ts = 429.049103
last_seq = 16620
current_ts = 429.066101
current_received_ts = 429.065765
current_seq = 16621
count = 170
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.066101
last_received_ts = 429.065765
last_seq = 16621
current_ts = 429.082764
current_received_ts = 429.082458
current_seq = 16622
count = 171
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.082764
last_received_ts = 429.082458
last_seq = 16622
current_ts = 429.099426
current_received_ts = 429.099121
current_seq = 16623
count = 172
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.099426
last_received_ts = 429.099121
last_seq = 16623
current_ts = 429.116089
current_received_ts = 429.115784
current_seq = 16624
count = 173
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.116089
last_received_ts = 429.115784
last_seq = 16624
current_ts = 429.132751
current_received_ts = 429.132446
current_seq = 16625
count = 174
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.132751
last_received_ts = 429.132446
last_seq = 16625
current_ts = 429.149445
current_received_ts = 429.149109
current_seq = 16626
count = 175
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.149445
last_received_ts = 429.149109
last_seq = 16626
current_ts = 429.166077
current_received_ts = 429.165771
current_seq = 16627
count = 176
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.166077
last_received_ts = 429.165771
last_seq = 16627
current_ts = 429.182739
current_received_ts = 429.182465
current_seq = 16628
count = 177
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.182739
last_received_ts = 429.182465
last_seq = 16628
current_ts = 429.199402
current_received_ts = 429.199127
current_seq = 16629
count = 178
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 48us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.199402
last_received_ts = 429.199127
last_seq = 16629
current_ts = 429.216095
current_received_ts = 429.215820
current_seq = 16630
count = 179
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 43us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.216095
last_received_ts = 429.215820
last_seq = 16630
current_ts = 429.232758
current_received_ts = 429.232483
current_seq = 16631
count = 180
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 43us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.232758
last_received_ts = 429.232483
last_seq = 16631
current_ts = 429.249420
current_received_ts = 429.249146
current_seq = 16632
count = 181
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 34us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.249420
last_received_ts = 429.249146
last_seq = 16632
current_ts = 429.266083
current_received_ts = 429.265808
current_seq = 16633
count = 182
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 32us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.266083
last_received_ts = 429.265808
last_seq = 16633
current_ts = 429.282745
current_received_ts = 429.282471
current_seq = 16634
count = 183
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 31us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.282745
last_received_ts = 429.282471
last_seq = 16634
current_ts = 429.299408
current_received_ts = 429.299133
current_seq = 16635
count = 184
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 31us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.299408
last_received_ts = 429.299133
last_seq = 16635
current_ts = 429.316071
current_received_ts = 429.315796
current_seq = 16636
count = 185
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.316071
last_received_ts = 429.315796
last_seq = 16636
current_ts = 429.332764
current_received_ts = 429.332458
current_seq = 16637
count = 186
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 30us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.332764
last_received_ts = 429.332458
last_seq = 16637
current_ts = 429.349426
current_received_ts = 429.349152
current_seq = 16638
count = 187
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 38us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.349426
last_received_ts = 429.349152
last_seq = 16638
current_ts = 429.366089
current_received_ts = 429.365784
current_seq = 16639
count = 188
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 27us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.366089
last_received_ts = 429.365784
last_seq = 16639
current_ts = 429.382751
current_received_ts = 429.382446
current_seq = 16640
count = 189
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 35us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.382751
last_received_ts = 429.382446
last_seq = 16640
current_ts = 429.399414
current_received_ts = 429.399109
current_seq = 16641
count = 190
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 35us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.399414
last_received_ts = 429.399109
last_seq = 16641
current_ts = 429.416077
current_received_ts = 429.415741
current_seq = 16642
count = 191
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.416077
last_received_ts = 429.415741
last_seq = 16642
current_ts = 429.432739
current_received_ts = 429.432404
current_seq = 16643
count = 192
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.432739
last_received_ts = 429.432404
last_seq = 16643
current_ts = 429.449432
current_received_ts = 429.449066
current_seq = 16644
count = 193
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.449432
last_received_ts = 429.449066
last_seq = 16644
current_ts = 429.466095
current_received_ts = 429.465729
current_seq = 16645
count = 194
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.466095
last_received_ts = 429.465729
last_seq = 16645
current_ts = 429.482758
current_received_ts = 429.482391
current_seq = 16646
count = 195
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.482758
last_received_ts = 429.482391
last_seq = 16646
current_ts = 429.499420
current_received_ts = 429.499054
current_seq = 16647
count = 196
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.499420
last_received_ts = 429.499054
last_seq = 16647
current_ts = 429.516083
current_received_ts = 429.515717
current_seq = 16648
count = 197
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.516083
last_received_ts = 429.515717
last_seq = 16648
current_ts = 429.532745
current_received_ts = 429.532379
current_seq = 16649
count = 198
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.532745
last_received_ts = 429.532379
last_seq = 16649
current_ts = 429.549438
current_received_ts = 429.549042
current_seq = 16650
count = 199
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.549438
last_received_ts = 429.549042
last_seq = 16650
current_ts = 429.566071
current_received_ts = 429.565704
current_seq = 16651
count = 200
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.566071
last_received_ts = 429.565704
last_seq = 16651
current_ts = 429.582764
current_received_ts = 429.582397
current_seq = 16652
count = 201
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.582764
last_received_ts = 429.582397
last_seq = 16652
current_ts = 429.599426
current_received_ts = 429.599060
current_seq = 16653
count = 202
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.599426
last_received_ts = 429.599060
last_seq = 16653
current_ts = 429.616089
current_received_ts = 429.615723
current_seq = 16654
count = 203
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.616089
last_received_ts = 429.615723
last_seq = 16654
current_ts = 429.632751
current_received_ts = 429.632385
current_seq = 16655
count = 204
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.632751
last_received_ts = 429.632385
last_seq = 16655
current_ts = 429.649414
current_received_ts = 429.649048
current_seq = 16656
count = 205
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.649414
last_received_ts = 429.649048
last_seq = 16656
current_ts = 429.666077
current_received_ts = 429.665710
current_seq = 16657
count = 206
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.666077
last_received_ts = 429.665710
last_seq = 16657
current_ts = 429.682739
current_received_ts = 429.682373
current_seq = 16658
count = 207
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.682739
last_received_ts = 429.682373
last_seq = 16658
current_ts = 429.699402
current_received_ts = 429.699066
current_seq = 16659
count = 208
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.699402
last_received_ts = 429.699066
last_seq = 16659
current_ts = 429.716095
current_received_ts = 429.715729
current_seq = 16660
count = 209
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.716095
last_received_ts = 429.715729
last_seq = 16660
current_ts = 429.732758
current_received_ts = 429.732391
current_seq = 16661
count = 210
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.732758
last_received_ts = 429.732391
last_seq = 16661
current_ts = 429.749420
current_received_ts = 429.749054
current_seq = 16662
count = 211
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.749420
last_received_ts = 429.749054
last_seq = 16662
current_ts = 429.766083
current_received_ts = 429.765717
current_seq = 16663
count = 212
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.766083
last_received_ts = 429.765717
last_seq = 16663
current_ts = 429.782745
current_received_ts = 429.782379
current_seq = 16664
count = 213
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.782745
last_received_ts = 429.782379
last_seq = 16664
current_ts = 429.799408
current_received_ts = 429.799042
current_seq = 16665
count = 214
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.799408
last_received_ts = 429.799042
last_seq = 16665
current_ts = 429.816071
current_received_ts = 429.815704
current_seq = 16666
count = 215
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.816071
last_received_ts = 429.815704
last_seq = 16666
current_ts = 429.832733
current_received_ts = 429.832367
current_seq = 16667
count = 216
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.832733
last_received_ts = 429.832367
last_seq = 16667
current_ts = 429.849426
current_received_ts = 429.849152
current_seq = 16668
count = 217
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 76us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.849426
last_received_ts = 429.849152
last_seq = 16668
current_ts = 429.866089
current_received_ts = 429.865723
current_seq = 16669
count = 218
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 37us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.866089
last_received_ts = 429.865723
last_seq = 16669
current_ts = 429.882751
current_received_ts = 429.882385
current_seq = 16670
count = 219
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.882751
last_received_ts = 429.882385
last_seq = 16670
current_ts = 429.899414
current_received_ts = 429.899139
current_seq = 16671
count = 220
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.899414
last_received_ts = 429.899139
last_seq = 16671
current_ts = 429.916077
current_received_ts = 429.915771
current_seq = 16672
count = 221
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.916077
last_received_ts = 429.915771
last_seq = 16672
current_ts = 429.932739
current_received_ts = 429.932434
current_seq = 16673
count = 222
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.932739
last_received_ts = 429.932434
last_seq = 16673
current_ts = 429.949402
current_received_ts = 429.949127
current_seq = 16674
count = 223
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.949402
last_received_ts = 429.949127
last_seq = 16674
current_ts = 429.966095
current_received_ts = 429.965790
current_seq = 16675
count = 224
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.966095
last_received_ts = 429.965790
last_seq = 16675
current_ts = 429.982758
current_received_ts = 429.982452
current_seq = 16676
count = 225
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.982758
last_received_ts = 429.982452
last_seq = 16676
current_ts = 429.999420
current_received_ts = 429.999115
current_seq = 16677
count = 226
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 429.999420
last_received_ts = 429.999115
last_seq = 16677
current_ts = 430.016083
current_received_ts = 430.015778
current_seq = 16678
count = 227
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.016083
last_received_ts = 430.015778
last_seq = 16678
current_ts = 430.032745
current_received_ts = 430.032440
current_seq = 16679
count = 228
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.032745
last_received_ts = 430.032440
last_seq = 16679
current_ts = 430.049408
current_received_ts = 430.049103
current_seq = 16680
count = 229
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.049408
last_received_ts = 430.049103
last_seq = 16680
current_ts = 430.066101
current_received_ts = 430.065765
current_seq = 16681
count = 230
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.066101
last_received_ts = 430.065765
last_seq = 16681
current_ts = 430.082733
current_received_ts = 430.082458
current_seq = 16682
count = 231
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.082733
last_received_ts = 430.082458
last_seq = 16682
current_ts = 430.099396
current_received_ts = 430.099121
current_seq = 16683
count = 232
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.099396
last_received_ts = 430.099121
last_seq = 16683
current_ts = 430.116089
current_received_ts = 430.115784
current_seq = 16684
count = 233
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.116089
last_received_ts = 430.115784
last_seq = 16684
current_ts = 430.132751
current_received_ts = 430.132446
current_seq = 16685
count = 234
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.132751
last_received_ts = 430.132446
last_seq = 16685
current_ts = 430.149414
current_received_ts = 430.149109
current_seq = 16686
count = 235
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.149414
last_received_ts = 430.149109
last_seq = 16686
current_ts = 430.166077
current_received_ts = 430.165771
current_seq = 16687
count = 236
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.166077
last_received_ts = 430.165771
last_seq = 16687
current_ts = 430.182739
current_received_ts = 430.182434
current_seq = 16688
count = 237
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.182739
last_received_ts = 430.182434
last_seq = 16688
current_ts = 430.199402
current_received_ts = 430.199127
current_seq = 16689
count = 238
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.199402
last_received_ts = 430.199127
last_seq = 16689
current_ts = 430.216064
current_received_ts = 430.215759
current_seq = 16690
count = 239
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 26us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.216064
last_received_ts = 430.215759
last_seq = 16690
current_ts = 430.232758
current_received_ts = 430.232452
current_seq = 16691
count = 240
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.232758
last_received_ts = 430.232452
last_seq = 16691
current_ts = 430.249420
current_received_ts = 430.249115
current_seq = 16692
count = 241
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 25us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.249420
last_received_ts = 430.249115
last_seq = 16692
current_ts = 430.266083
current_received_ts = 430.265778
current_seq = 16693
count = 242
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 24us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.266083
last_received_ts = 430.265778
last_seq = 16693
current_ts = 430.282745
current_received_ts = 430.282440
current_seq = 16694
count = 243
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 243us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.282745
last_received_ts = 430.282440
last_seq = 16694
current_ts = 430.299408
current_received_ts = 430.299103
current_seq = 16695
count = 244
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 258us
(kms_flip:9139) DEBUG: name = flip
last_ts = 430.299408
last_received_ts = 430.299103
last_seq = 16695
current_ts = 430.316071
current_received_ts = 430.315765
current_seq = 16696
count = 245
seq_step = 1
(kms_flip:9139) DEBUG: Vblank took 520us
(kms_flip:9139) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:9139) CRITICAL: Failed assertion: end - start < 500
(kms_flip:9139) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:9139) igt_core-INFO: Stack trace:
(kms_flip:9139) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:9139) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:9139) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:9139) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:9139) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:9139) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:9139) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:9139) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A3: FAIL (4.949s)
Dmesg
<6> [426.136889] Console: switching to colour dummy device 80x25
<6> [426.137174] [IGT] kms_flip: executing
<7> [426.165512] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1]
<7> [426.165519] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:507:DP-1]
<7> [426.169688] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1] disconnected
<7> [426.169979] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:HDMI-A-1]
<7> [426.169982] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:520:HDMI-A-1]
<7> [426.174130] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:HDMI-A-1] disconnected
<7> [426.174459] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:526:DP-2]
<7> [426.174463] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:526:DP-2]
<7> [426.179066] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:526:DP-2] disconnected
<7> [426.179374] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:HDMI-A-2]
<7> [426.179377] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:534:HDMI-A-2]
<7> [426.183502] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:HDMI-A-2] disconnected
<7> [426.184198] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:DP-3]
<7> [426.184203] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:542:DP-3]
<7> [426.188408] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:DP-3] disconnected
<7> [426.188822] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:550:HDMI-A-4]
<7> [426.188826] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:550:HDMI-A-4]
<7> [426.192984] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:550:HDMI-A-4] disconnected
<7> [426.193502] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1]
<7> [426.193506] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:507:DP-1]
<7> [426.197858] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1] disconnected
<7> [426.197869] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:HDMI-A-1]
<7> [426.197872] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:520:HDMI-A-1]
<7> [426.202102] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:HDMI-A-1] disconnected
<7> [426.202112] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:526:DP-2]
<7> [426.202114] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:526:DP-2]
<7> [426.206318] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:526:DP-2] disconnected
<7> [426.206329] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:HDMI-A-2]
<7> [426.206332] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:534:HDMI-A-2]
<7> [426.210610] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:HDMI-A-2] disconnected
<7> [426.210620] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:538:HDMI-A-3]
<7> [426.210624] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [426.235683] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HDMI: DVI dual 0, max TMDS clock 340000 kHz
<7> [426.235687] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HDMI sink does deep color 30.
<7> [426.235689] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HDMI sink does deep color 36.
<7> [426.235692] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] Assigning HDMI sink color depth as 12 bpc.
<7> [426.235694] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HDMI sink does YCRCB444 in deep color.
<7> [426.235696] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [426.235700] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] ELD monitor 28E850
<7> [426.235703] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [426.235706] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:538:HDMI-A-3] ELD size 32, SAD count 1
<7> [426.236068] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0040 w(1)
<7> [426.236150] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK on first message, retry
<7> [426.236585] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0040 w(1)
<7> [426.236662] xe 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [426.237099] xe 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [426.237106] xe 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [426.237110] xe 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [426.237116] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:538:HDMI-A-3] probed modes:
<7> [426.237119] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [426.237121] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237123] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237125] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5
<7> [426.237128] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [426.237130] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [426.237132] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2162 2172 2250 0x40 0x5
<7> [426.237134] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237136] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237138] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [426.237140] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 3888 3920 4400 2160 2164 2170 2250 0x40 0x9
<7> [426.237142] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237144] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [426.237146] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [426.237148] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [426.237151] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [426.237153] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 60 336720 2880 2928 2960 3040 1800 1803 1809 1846 0x40 0x9
<7> [426.237155] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x9
<7> [426.237157] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x9
<7> [426.237159] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [426.237161] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [426.237163] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [426.237165] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [426.237168] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [426.237170] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [426.237172] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [426.237174] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [426.237176] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [426.237178] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [426.237180] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 61 110809 1280 1360 1496 1712 1024 1025 1028 1061 0x0 0x6
<7> [426.237183] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [426.237185] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [426.237187] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [426.237189] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [426.237198] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [426.237200] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [426.237202] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [426.237205] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [426.237207] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [426.237209] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [426.237212] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [426.237214] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [426.237216] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [426.237218] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [426.237221] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [426.237223] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [426.237225] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [426.237227] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [426.237229] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [426.237231] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [426.237233] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [426.237258] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:DP-3]
<7> [426.237262] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:542:DP-3]
<7> [426.241563] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:DP-3] disconnected
<7> [426.241574] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:550:HDMI-A-4]
<7> [426.241577] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:550:HDMI-A-4]
<7> [426.245796] xe 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:550:HDMI-A-4] disconnected
<6> [426.249215] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [426.252904] [IGT] kms_flip: starting dynamic subtest A-HDMI-A3
<7> [426.253451] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:556]
<7> [426.253726] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [426.301127] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [426.301233] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [426.301376] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [426.301491] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [426.301601] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [426.301708] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [426.301811] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [426.301913] 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 1920, found 0)
<7> [426.302015] 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 2200, found 0)
<7> [426.302117] 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 1920, found 0)
<7> [426.302226] 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 2200, found 0)
<7> [426.302328] 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 2008, found 0)
<7> [426.302429] 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 2052, found 0)
<7> [426.302530] 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 1080, found 0)
<7> [426.302630] 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 1080, found 0)
<7> [426.302729] 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 1083, found 0)
<7> [426.302827] 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 1088, found 0)
<7> [426.302928] 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 1125, found 0)
<7> [426.303026] 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 1125, found 0)
<7> [426.303124] 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 1920, found 0)
<7> [426.303231] 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 2200, found 0)
<7> [426.303329] 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 1920, found 0)
<7> [426.303429] 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 2200, found 0)
<7> [426.303528] 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 2008, found 0)
<7> [426.303627] 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 2052, found 0)
<7> [426.303732] 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 1080, found 0)
<7> [426.303835] 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 1080, found 0)
<7> [426.303936] 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 1083, found 0)
<7> [426.304036] 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 1088, found 0)
<7> [426.304135] 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 1125, found 0)
<7> [426.304241] 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 1125, found 0)
<7> [426.304340] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [426.304438] 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 1, found 0)
<7> [426.304536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [426.304634] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [426.304731] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [426.304830] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [426.304929] 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 148500, found 0)
<7> [426.305028] 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 148500, found 0)
<7> [426.305125] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [426.305259] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [426.305359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [426.305456] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in avi infoframe
<7> [426.305553] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.305650] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [426.305652] xe 0000:03:00.0: colorspace: RGB
<7> [426.305653] xe 0000:03:00.0: scan mode: Underscan
<7> [426.305654] xe 0000:03:00.0: colorimetry: No Data
<7> [426.305655] xe 0000:03:00.0: picture aspect: No Data
<7> [426.305657] xe 0000:03:00.0: active aspect: Same as Picture
<7> [426.305658] xe 0000:03:00.0: itc: No Data
<7> [426.305659] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [426.305660] xe 0000:03:00.0: quantization range: Full
<7> [426.305661] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [426.305662] xe 0000:03:00.0: video code: 0
<7> [426.305663] xe 0000:03:00.0: ycc quantization range: Full
<7> [426.305664] xe 0000:03:00.0: hdmi content type: Graphics
<7> [426.305665] xe 0000:03:00.0: pixel repeat: 0
<7> [426.305666] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [426.305668] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.305764] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in spd infoframe
<7> [426.305859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.305954] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [426.305955] xe 0000:03:00.0: vendor: Intel
<7> [426.305957] xe 0000:03:00.0: product: Discrete gfx
<7> [426.305958] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [426.305959] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.306055] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hdmi infoframe
<7> [426.306152] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.306257] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [426.306259] xe 0000:03:00.0: empty frame
<7> [426.306260] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.306358] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [426.306455] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:150:pipe A] releasing TC PLL 3
<7> [426.306590] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [426.306678] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [426.306783] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 4037) -> ( 0 - 0), size 4037 -> 0
<7> [426.306869] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [426.306950] 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> [426.307030] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] lines 1, 3, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [426.307107] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] blocks 16, 49, 145, 145, 273, 273, 273, 273, 30, 241, 255 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [426.307183] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] min_ddb 19, 55, 161, 161, 302, 302, 0, 0, 31, 267, 267 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [426.307281] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 0 num active planes 0
<7> [426.307393] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [426.307498] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [426.307599] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [426.307701] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [426.307817] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [426.307934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: no [modeset]
<7> [426.308043] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [426.308147] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [426.308259] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [426.308362] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [426.308463] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [426.308565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [426.309248] 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:150:pipe A]
<7> [426.329058] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [426.350103] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x1, on? 1) for [CRTC:150:pipe A]
<7> [426.352614] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [426.353507] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [426.354053] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [426.354631] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [426.355104] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [426.355602] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [426.356036] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [426.356460] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [426.356861] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [426.357246] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [426.357615] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [426.357951] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [426.358539] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [426.358872] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [426.359174] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [426.359522] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [426.359819] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [426.360090] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [426.360646] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [426.361098] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [426.361570] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [426.362357] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [426.362635] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [426.362914] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [426.363193] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [426.363701] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [426.363937] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [426.364133] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [426.364441] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [426.364499] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [426.364626] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [426.364864] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [426.365090] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:150:pipe A]
<7> [426.365339] 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> [426.365552] 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> [426.365740] 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> [426.365921] 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> [426.366094] 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 0x00000040)
<7> [426.366283] 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> [426.366458] 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> [426.366613] 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> [426.366768] 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> [426.366919] 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> [426.367067] 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> [426.367229] 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> [426.367375] 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> [426.367517] 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> [426.367651] 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 1083)
<7> [426.367783] 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 1088)
<7> [426.367907] 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> [426.368031] 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> [426.368151] 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> [426.368285] 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> [426.368406] 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> [426.368526] 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> [426.368639] 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> [426.368752] 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> [426.368863] 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> [426.368973] 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> [426.369083] 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 1083)
<7> [426.369194] 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 1088)
<7> [426.369314] 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> [426.369419] 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> [426.369523] 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> [426.369616] 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> [426.369707] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [426.369798] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [426.369890] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [426.369982] 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> [426.370074] 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> [426.370165] 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> [426.370266] 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 222750)
<7> [426.370361] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [426.370453] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [426.370544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in avi infoframe
<7> [426.370634] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.370725] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.370815] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [426.370816] xe 0000:03:00.0: colorspace: RGB
<7> [426.370818] xe 0000:03:00.0: scan mode: Underscan
<7> [426.370819] xe 0000:03:00.0: colorimetry: No Data
<7> [426.370820] xe 0000:03:00.0: picture aspect: No Data
<7> [426.370821] xe 0000:03:00.0: active aspect: Same as Picture
<7> [426.370822] xe 0000:03:00.0: itc: No Data
<7> [426.370823] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [426.370824] xe 0000:03:00.0: quantization range: Full
<7> [426.370825] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [426.370826] xe 0000:03:00.0: video code: 0
<7> [426.370827] xe 0000:03:00.0: ycc quantization range: Full
<7> [426.370828] xe 0000:03:00.0: hdmi content type: Graphics
<7> [426.370830] xe 0000:03:00.0: pixel repeat: 0
<7> [426.370831] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [426.370832] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in spd infoframe
<7> [426.370924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.371014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.371105] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [426.371106] xe 0000:03:00.0: vendor: Intel
<7> [426.371107] xe 0000:03:00.0: product: Discrete gfx
<7> [426.371108] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [426.371109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hdmi infoframe
<7> [426.371207] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [426.371300] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [426.371390] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [426.371391] xe 0000:03:00.0: empty frame
<7> [426.371393] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [426.371514] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [426.371598] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [426.371701] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [426.371779] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [426.371855] 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> [426.371930] 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 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [426.372004] 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 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [426.372077] 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 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [426.372162] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 594000 num active planes 1
<7> [426.372277] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [426.372380] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [426.372478] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [426.372576] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [426.372687] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [426.372796] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated TC PLL 3
<7> [426.372894] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving TC PLL 3
<7> [426.372989] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [426.373092] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [426.373192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [426.373305] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [426.373405] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [426.373500] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [426.373595] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [426.373690] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [426.373783] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [426.373877] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [426.373878] xe 0000:03:00.0: colorspace: RGB
<7> [426.373880] xe 0000:03:00.0: scan mode: Underscan
<7> [426.373881] xe 0000:03:00.0: colorimetry: No Data
<7> [426.373882] xe 0000:03:00.0: picture aspect: No Data
<7> [426.373883] xe 0000:03:00.0: active aspect: Same as Picture
<7> [426.373884] xe 0000:03:00.0: itc: No Data
<7> [426.373885] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [426.373886] xe 0000:03:00.0: quantization range: Full
<7> [426.373887] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [426.373888] xe 0000:03:00.0: video code: 0
<7> [426.373889] xe 0000:03:00.0: ycc quantization range: Full
<7> [426.373890] xe 0000:03:00.0: hdmi content type: Graphics
<7> [426.373891] xe 0000:03:00.0: pixel repeat: 0
<7> [426.373892] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [426.373894] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [426.373895] xe 0000:03:00.0: vendor: Intel
<7> [426.373896] xe 0000:03:00.0: product: Discrete gfx
<7> [426.373897] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [426.373898] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [426.373900] xe 0000:03:00.0: empty frame
<7> [426.373901] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [426.374000] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [426.374095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [426.374188] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [426.374294] 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: 42, vsync end: 37
<7> [426.374392] 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> [426.374484] 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> [426.374576] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [426.374668] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [426.374759] 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=1083-1088 vt=1125, flags=0x9
<7> [426.374851] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [426.374942] 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=1083-1088 vt=1125, flags=0x9
<7> [426.375033] 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> [426.375124] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [426.375221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [426.375333] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [426.375425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [426.375515] 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> [426.375605] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [426.375695] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [426.375791] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [426.375880] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [426.375970] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [426.376060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [426.376151] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [426.376267] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [426.376357] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [426.376447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [426.376535] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [426.376624] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [426.376714] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [426.376802] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [426.376891] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [426.376980] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [426.377070] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [426.377160] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [426.377261] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377352] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377443] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377533] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [426.377622] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [426.377712] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377802] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377892] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [426.377981] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [426.378071] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [426.378161] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [426.378262] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [426.378358] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [426.378448] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [426.378538] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [426.378629] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [426.378719] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [426.378809] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [426.379456] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [426.379605] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [426.379706] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [426.379845] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [426.379935] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [426.380022] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [426.380105] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [426.380188] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [426.380286] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [426.380374] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [426.380458] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [426.380538] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [426.380617] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [426.380696] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [426.380775] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [426.380853] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [426.380931] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [426.381008] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [426.381086] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [426.381172] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [426.381348] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [426.381427] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [426.381530] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [426.381623] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [426.384123] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [426.401657] 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:150:pipe A], 32 bytes ELD
<7> [426.418279] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [426.418468] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [426.419000] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [426.419423] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<6> [430.740447] xe 0000:03:00.0: [drm] PL2 disabled for channel 0, val 0x00000000
<7> [430.747221] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x22222020
<7> [430.747403] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x22222121
<6> [431.209645] [IGT] kms_flip: finished subtest A-HDMI-A3, FAIL
Created at 2026-03-19 19:30:15