Results for igt@kms_flip@flip-vs-blocking-wf-vblank@b-hdmi-a3

Result: Fail

i915_display_info14 igt_runner14 results14.json results14-xe-load.json guc_logs14.tar i915_display_info_post_exec14 boot14 dmesg14

DetailValue
Duration 20.93 seconds
Hostname 
shard-bmg-5
Igt-Version 
IGT-Version: 2.3-g3ab8128cb (x86_64) (Linux: 7.0.0-rc4-lgci-xe-xe-4738-90a49598c224945b5-debug+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Expected frametime: 16667us; measured 16668.0us +- 5.342us accuracy 0.10%
Event flip: expected 452, counted 460, passrate = 26.09%, encoder type 2
Event vblank: expected 452, counted 460, passrate = 23.91%, encoder type 2
Expected frametime: 16667us; measured 16510.8us +- 11.490us accuracy 0.21%
vblank interval differs from modeline! expected 16666.7us, measured 16511us +- 11.490us, difference 155.9us (13.6 sigma)
Event flip: expected 461, counted 460, passrate = 78.26%, encoder type 2
Event vblank: expected 461, counted 460, passrate = 76.09%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2126 __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 B-HDMI-A3: FAIL (20.932s)
Err 
Starting dynamic subtest: B-HDMI-A3
(kms_flip:7086) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7086) CRITICAL: Failed assertion: !retried
Dynamic subtest B-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:7086) DEBUG: name = vblank
last_ts = 257.627441
last_received_ts = 257.627380
last_seq = 683
current_ts = 257.791382
current_received_ts = 257.791504
current_seq = 693
count = 41
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 257.627441/683, current 257.791382/693: elapsed=163963.0us expected=166680.0us +- 833.4us, error 1.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 257.627441/683, current 257.791382/693: elapsed=163963.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = flip
last_ts = 257.643829
last_received_ts = 257.791565
last_seq = 684
current_ts = 257.807831
current_received_ts = 257.955780
current_seq = 694
count = 42
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 257.643829/684, current 257.807831/694: elapsed=163993.0us expected=166680.0us +- 833.4us, error 1.6%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 257.643829/684, current 257.807831/694: elapsed=163993.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 257.791382
last_received_ts = 257.791504
last_seq = 693
current_ts = 257.955597
current_received_ts = 257.955719
current_seq = 703
count = 42
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 257.791382/693, current 257.955597/703: elapsed=164209.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 257.791382/693, current 257.955597/703: elapsed=164209.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = flip
last_ts = 257.807831
last_received_ts = 257.955780
last_seq = 694
current_ts = 257.972015
current_received_ts = 258.119934
current_seq = 704
count = 43
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 257.807831/694, current 257.972015/704: elapsed=164194.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 257.807831/694, current 257.972015/704: elapsed=164194.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 257.955597
last_received_ts = 257.955719
last_seq = 703
current_ts = 258.119812
current_received_ts = 258.119873
current_seq = 713
count = 43
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 257.955597/703, current 258.119812/713: elapsed=164211.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 257.955597/703, current 258.119812/713: elapsed=164211.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = flip
last_ts = 257.972015
last_received_ts = 258.119934
last_seq = 704
current_ts = 258.136230
current_received_ts = 258.284119
current_seq = 714
count = 44
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 257.972015/704, current 258.136230/714: elapsed=164212.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 257.972015/704, current 258.136230/714: elapsed=164212.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 258.119812
last_received_ts = 258.119873
last_seq = 713
current_ts = 258.284027
current_received_ts = 258.284058
current_seq = 723
count = 44
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 258.119812/713, current 258.284027/723: elapsed=164200.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 258.119812/713, current 258.284027/723: elapsed=164200.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = flip
last_ts = 258.136230
last_received_ts = 258.284119
last_seq = 714
current_ts = 258.300446
current_received_ts = 258.448425
current_seq = 724
count = 45
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 258.136230/714, current 258.300446/724: elapsed=164210.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 258.136230/714, current 258.300446/724: elapsed=164210.0us expected=166680.0us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 258.284027
last_received_ts = 258.284058
last_seq = 723
current_ts = 258.448212
current_received_ts = 258.448334
current_seq = 733
count = 45
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 258.284027/723, current 258.448212/733: elapsed=164208.0us expected=166680.0us +- 833.4us, error 1.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 258.284027/723, current 258.448212/733: elapsed=164208.0us expected=166680.0us
(kms_flip:7086) INFO: Event flip: expected 452, counted 460, passrate = 26.09%, encoder type 2
(kms_flip:7086) DEBUG: dropped frames, expected 452, counted 460, passrate = 26.09%, encoder type 2
(kms_flip:7086) INFO: Event vblank: expected 452, counted 460, passrate = 23.91%, encoder type 2
(kms_flip:7086) DEBUG: dropped frames, expected 452, counted 460, passrate = 23.91%, encoder type 2
(kms_flip:7086) DEBUG: Retrying without a hotplug event
(kms_flip:7086) DEBUG: No stale events found
(kms_flip:7086) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:7086) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:7086) INFO: Expected frametime: 16667us; measured 16510.8us +- 11.490us accuracy 0.21%
(kms_flip:7086) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16511us +- 11.490us, difference 155.9us (13.6 sigma)
(kms_flip:7086) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 261.920502
current_received_ts = 262.069336
current_seq = 939
count = 0
seq_step = 1
(kms_flip:7086) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 262.069183
current_received_ts = 262.069275
current_seq = 948
count = 0
seq_step = 10
(kms_flip:7086) DEBUG: name = flip
last_ts = 261.920502
last_received_ts = 262.069336
last_seq = 939
current_ts = 262.085693
current_received_ts = 262.234528
current_seq = 949
count = 1
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 261.920502/939, current 262.085693/949: elapsed=165200.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.069183
last_received_ts = 262.069275
last_seq = 948
current_ts = 262.234375
current_received_ts = 262.234436
current_seq = 958
count = 1
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.069183/948, current 262.234375/958: elapsed=165190.0us expected=165108.1us +- 825.5us, error 0.0%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.085693
last_received_ts = 262.234528
last_seq = 949
current_ts = 262.250885
current_received_ts = 262.399719
current_seq = 959
count = 2
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.085693/949, current 262.250885/959: elapsed=165194.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.234375
last_received_ts = 262.234436
last_seq = 958
current_ts = 262.399567
current_received_ts = 262.399628
current_seq = 968
count = 2
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.234375/958, current 262.399567/968: elapsed=165192.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.250885
last_received_ts = 262.399719
last_seq = 959
current_ts = 262.416077
current_received_ts = 262.564850
current_seq = 969
count = 3
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.250885/959, current 262.416077/969: elapsed=165194.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.399567
last_received_ts = 262.399628
last_seq = 968
current_ts = 262.564758
current_received_ts = 262.564789
current_seq = 978
count = 3
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.399567/968, current 262.564758/978: elapsed=165196.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.416077
last_received_ts = 262.564850
last_seq = 969
current_ts = 262.581268
current_received_ts = 262.730133
current_seq = 979
count = 4
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.416077/969, current 262.581268/979: elapsed=165195.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.564758
last_received_ts = 262.564789
last_seq = 978
current_ts = 262.729950
current_received_ts = 262.730042
current_seq = 988
count = 4
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.564758/978, current 262.729950/988: elapsed=165185.0us expected=165108.1us +- 825.5us, error 0.0%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.581268
last_received_ts = 262.730133
last_seq = 979
current_ts = 262.746460
current_received_ts = 262.895447
current_seq = 989
count = 5
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.581268/979, current 262.746460/989: elapsed=165196.0us expected=165108.1us +- 825.5us, error 0.1%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.729950
last_received_ts = 262.730042
last_seq = 988
current_ts = 262.895294
current_received_ts = 262.895386
current_seq = 998
count = 5
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.729950/988, current 262.895294/998: elapsed=165372.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.746460
last_received_ts = 262.895447
last_seq = 989
current_ts = 262.911835
current_received_ts = 263.060638
current_seq = 999
count = 6
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.746460/989, current 262.911835/999: elapsed=165366.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 262.895294
last_received_ts = 262.895386
last_seq = 998
current_ts = 263.060669
current_received_ts = 263.060577
current_seq = 1008
count = 6
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 262.895294/998, current 263.060669/1008: elapsed=165363.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 262.911835
last_received_ts = 263.060638
last_seq = 999
current_ts = 263.077209
current_received_ts = 263.226196
current_seq = 1009
count = 7
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 262.911835/999, current 263.077209/1009: elapsed=165373.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.060669
last_received_ts = 263.060577
last_seq = 1008
current_ts = 263.226044
current_received_ts = 263.226135
current_seq = 1018
count = 7
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.060669/1008, current 263.226044/1018: elapsed=165365.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.077209
last_received_ts = 263.226196
last_seq = 1009
current_ts = 263.242584
current_received_ts = 263.391571
current_seq = 1019
count = 8
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.077209/1009, current 263.242584/1019: elapsed=165373.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.226044
last_received_ts = 263.226135
last_seq = 1018
current_ts = 263.391418
current_received_ts = 263.391510
current_seq = 1028
count = 8
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.226044/1018, current 263.391418/1028: elapsed=165370.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.242584
last_received_ts = 263.391571
last_seq = 1019
current_ts = 263.407959
current_received_ts = 263.556793
current_seq = 1029
count = 9
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.242584/1019, current 263.407959/1029: elapsed=165364.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.391418
last_received_ts = 263.391510
last_seq = 1028
current_ts = 263.556793
current_received_ts = 263.556732
current_seq = 1038
count = 9
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.391418/1028, current 263.556793/1038: elapsed=165376.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.407959
last_received_ts = 263.556793
last_seq = 1029
current_ts = 263.573334
current_received_ts = 263.722290
current_seq = 1039
count = 10
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.407959/1029, current 263.573334/1039: elapsed=165371.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.556793
last_received_ts = 263.556732
last_seq = 1038
current_ts = 263.722137
current_received_ts = 263.722229
current_seq = 1048
count = 10
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.556793/1038, current 263.722137/1048: elapsed=165356.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.573334
last_received_ts = 263.722290
last_seq = 1039
current_ts = 263.738678
current_received_ts = 263.887817
current_seq = 1049
count = 11
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.573334/1039, current 263.738678/1049: elapsed=165357.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.722137
last_received_ts = 263.722229
last_seq = 1048
current_ts = 263.887634
current_received_ts = 263.887756
current_seq = 1058
count = 11
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.722137/1048, current 263.887634/1058: elapsed=165507.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.738678
last_received_ts = 263.887817
last_seq = 1049
current_ts = 263.904205
current_received_ts = 264.053345
current_seq = 1059
count = 12
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.738678/1049, current 263.904205/1059: elapsed=165527.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 263.887634
last_received_ts = 263.887756
last_seq = 1058
current_ts = 264.053162
current_received_ts = 264.053284
current_seq = 1068
count = 12
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 263.887634/1058, current 264.053162/1068: elapsed=165528.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 263.904205
last_received_ts = 264.053345
last_seq = 1059
current_ts = 264.069733
current_received_ts = 264.218872
current_seq = 1069
count = 13
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 263.904205/1059, current 264.069733/1069: elapsed=165519.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.053162
last_received_ts = 264.053284
last_seq = 1068
current_ts = 264.218689
current_received_ts = 264.218811
current_seq = 1078
count = 13
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.053162/1068, current 264.218689/1078: elapsed=165512.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.069733
last_received_ts = 264.218872
last_seq = 1069
current_ts = 264.235229
current_received_ts = 264.384216
current_seq = 1079
count = 14
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.069733/1069, current 264.235229/1079: elapsed=165521.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.218689
last_received_ts = 264.218811
last_seq = 1078
current_ts = 264.384216
current_received_ts = 264.384155
current_seq = 1088
count = 14
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.218689/1078, current 264.384216/1088: elapsed=165531.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.235229
last_received_ts = 264.384216
last_seq = 1079
current_ts = 264.400757
current_received_ts = 264.549927
current_seq = 1089
count = 15
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.235229/1079, current 264.400757/1089: elapsed=165519.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.384216
last_received_ts = 264.384155
last_seq = 1088
current_ts = 264.549744
current_received_ts = 264.549835
current_seq = 1098
count = 15
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.384216/1088, current 264.549744/1098: elapsed=165520.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.400757
last_received_ts = 264.549927
last_seq = 1089
current_ts = 264.566284
current_received_ts = 264.715424
current_seq = 1099
count = 16
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.400757/1089, current 264.566284/1099: elapsed=165519.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.549744
last_received_ts = 264.549835
last_seq = 1098
current_ts = 264.715271
current_received_ts = 264.715363
current_seq = 1108
count = 16
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.549744/1098, current 264.715271/1108: elapsed=165519.0us expected=165108.1us +- 825.5us, error 0.2%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.566284
last_received_ts = 264.715424
last_seq = 1099
current_ts = 264.731812
current_received_ts = 264.881104
current_seq = 1109
count = 17
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.566284/1099, current 264.731812/1109: elapsed=165527.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.715271
last_received_ts = 264.715363
last_seq = 1108
current_ts = 264.880890
current_received_ts = 264.880981
current_seq = 1118
count = 17
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.715271/1108, current 264.880890/1118: elapsed=165629.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.731812
last_received_ts = 264.881104
last_seq = 1109
current_ts = 264.897461
current_received_ts = 265.046692
current_seq = 1119
count = 18
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.731812/1109, current 264.897461/1119: elapsed=165648.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 264.880890
last_received_ts = 264.880981
last_seq = 1118
current_ts = 265.046539
current_received_ts = 265.046600
current_seq = 1128
count = 18
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 264.880890/1118, current 265.046539/1128: elapsed=165657.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 264.897461
last_received_ts = 265.046692
last_seq = 1119
current_ts = 265.063110
current_received_ts = 265.212219
current_seq = 1129
count = 19
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 264.897461/1119, current 265.063110/1129: elapsed=165655.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.046539
last_received_ts = 265.046600
last_seq = 1128
current_ts = 265.212219
current_received_ts = 265.212158
current_seq = 1138
count = 19
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.046539/1128, current 265.212219/1138: elapsed=165664.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.063110
last_received_ts = 265.212219
last_seq = 1129
current_ts = 265.228760
current_received_ts = 265.377991
current_seq = 1139
count = 20
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.063110/1129, current 265.228760/1139: elapsed=165660.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.212219
last_received_ts = 265.212158
last_seq = 1138
current_ts = 265.377869
current_received_ts = 265.377930
current_seq = 1148
count = 20
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.212219/1138, current 265.377869/1148: elapsed=165654.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.228760
last_received_ts = 265.377991
last_seq = 1139
current_ts = 265.394440
current_received_ts = 265.543610
current_seq = 1149
count = 21
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.228760/1139, current 265.394440/1149: elapsed=165654.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.377869
last_received_ts = 265.377930
last_seq = 1148
current_ts = 265.543518
current_received_ts = 265.543549
current_seq = 1158
count = 21
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.377869/1148, current 265.543518/1158: elapsed=165653.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.394440
last_received_ts = 265.543610
last_seq = 1149
current_ts = 265.560089
current_received_ts = 265.709320
current_seq = 1159
count = 22
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.394440/1149, current 265.560089/1159: elapsed=165651.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.543518
last_received_ts = 265.543549
last_seq = 1158
current_ts = 265.709167
current_received_ts = 265.709259
current_seq = 1168
count = 22
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.543518/1158, current 265.709167/1168: elapsed=165655.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.560089
last_received_ts = 265.709320
last_seq = 1159
current_ts = 265.725739
current_received_ts = 265.875153
current_seq = 1169
count = 23
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.560089/1159, current 265.725739/1169: elapsed=165659.0us expected=165108.1us +- 825.5us, error 0.3%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.709167
last_received_ts = 265.709259
last_seq = 1168
current_ts = 265.874908
current_received_ts = 265.875092
current_seq = 1178
count = 23
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.709167/1168, current 265.874908/1178: elapsed=165751.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.725739
last_received_ts = 265.875153
last_seq = 1169
current_ts = 265.891479
current_received_ts = 266.040894
current_seq = 1179
count = 24
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.725739/1169, current 265.891479/1179: elapsed=165750.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 265.874908
last_received_ts = 265.875092
last_seq = 1178
current_ts = 266.040680
current_received_ts = 266.040833
current_seq = 1188
count = 24
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 265.874908/1178, current 266.040680/1188: elapsed=165762.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 265.891479
last_received_ts = 266.040894
last_seq = 1179
current_ts = 266.057251
current_received_ts = 266.206207
current_seq = 1189
count = 25
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 265.891479/1179, current 266.057251/1189: elapsed=165781.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.040680
last_received_ts = 266.040833
last_seq = 1188
current_ts = 266.206451
current_received_ts = 266.206146
current_seq = 1198
count = 25
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.040680/1188, current 266.206451/1198: elapsed=165778.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.057251
last_received_ts = 266.206207
last_seq = 1189
current_ts = 266.223053
current_received_ts = 266.372345
current_seq = 1199
count = 26
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.057251/1189, current 266.223053/1199: elapsed=165776.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.206451
last_received_ts = 266.206146
last_seq = 1198
current_ts = 266.372223
current_received_ts = 266.372284
current_seq = 1208
count = 26
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.206451/1198, current 266.372223/1208: elapsed=165766.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.223053
last_received_ts = 266.372345
last_seq = 1199
current_ts = 266.388794
current_received_ts = 266.538147
current_seq = 1209
count = 27
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.223053/1199, current 266.388794/1209: elapsed=165762.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.372223
last_received_ts = 266.372284
last_seq = 1208
current_ts = 266.537994
current_received_ts = 266.538055
current_seq = 1218
count = 27
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.372223/1208, current 266.537994/1218: elapsed=165775.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.388794
last_received_ts = 266.538147
last_seq = 1209
current_ts = 266.554565
current_received_ts = 266.703888
current_seq = 1219
count = 28
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.388794/1209, current 266.554565/1219: elapsed=165776.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.537994
last_received_ts = 266.538055
last_seq = 1218
current_ts = 266.703766
current_received_ts = 266.703827
current_seq = 1228
count = 28
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.537994/1218, current 266.703766/1228: elapsed=165777.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.554565
last_received_ts = 266.703888
last_seq = 1219
current_ts = 266.720367
current_received_ts = 266.869720
current_seq = 1229
count = 29
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.554565/1219, current 266.720367/1229: elapsed=165773.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.703766
last_received_ts = 266.703827
last_seq = 1228
current_ts = 266.869629
current_received_ts = 266.869629
current_seq = 1238
count = 29
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.703766/1228, current 266.869629/1238: elapsed=165837.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.720367
last_received_ts = 266.869720
last_seq = 1229
current_ts = 266.886200
current_received_ts = 267.035614
current_seq = 1239
count = 30
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.720367/1229, current 266.886200/1239: elapsed=165846.0us expected=165108.1us +- 825.5us, error 0.4%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 266.869629
last_received_ts = 266.869629
last_seq = 1238
current_ts = 267.035492
current_received_ts = 267.035553
current_seq = 1248
count = 30
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 266.869629/1238, current 267.035492/1248: elapsed=165880.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = flip
last_ts = 266.886200
last_received_ts = 267.035614
last_seq = 1239
current_ts = 267.052063
current_received_ts = 267.201477
current_seq = 1249
count = 31
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 266.886200/1239, current 267.052063/1249: elapsed=165875.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.035492
last_received_ts = 267.035553
last_seq = 1248
current_ts = 267.201355
current_received_ts = 267.201416
current_seq = 1258
count = 31
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.035492/1248, current 267.201355/1258: elapsed=165865.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.052063
last_received_ts = 267.201477
last_seq = 1249
current_ts = 267.217957
current_received_ts = 267.367340
current_seq = 1259
count = 32
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.052063/1249, current 267.217957/1259: elapsed=165879.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.201355
last_received_ts = 267.201416
last_seq = 1258
current_ts = 267.367249
current_received_ts = 267.367279
current_seq = 1268
count = 32
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.201355/1258, current 267.367249/1268: elapsed=165883.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.217957
last_received_ts = 267.367340
last_seq = 1259
current_ts = 267.383820
current_received_ts = 267.533203
current_seq = 1269
count = 33
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.217957/1259, current 267.383820/1269: elapsed=165872.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.367249
last_received_ts = 267.367279
last_seq = 1268
current_ts = 267.533112
current_received_ts = 267.533142
current_seq = 1278
count = 33
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.367249/1268, current 267.533112/1278: elapsed=165869.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.383820
last_received_ts = 267.533203
last_seq = 1269
current_ts = 267.549713
current_received_ts = 267.699158
current_seq = 1279
count = 34
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.383820/1269, current 267.549713/1279: elapsed=165878.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.533112
last_received_ts = 267.533142
last_seq = 1278
current_ts = 267.698975
current_received_ts = 267.699097
current_seq = 1288
count = 34
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.533112/1278, current 267.698975/1288: elapsed=165871.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.549713
last_received_ts = 267.699158
last_seq = 1279
current_ts = 267.715576
current_received_ts = 267.865082
current_seq = 1289
count = 35
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.549713/1279, current 267.715576/1289: elapsed=165879.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.698975
last_received_ts = 267.699097
last_seq = 1288
current_ts = 267.864929
current_received_ts = 267.864960
current_seq = 1298
count = 35
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.698975/1288, current 267.864929/1298: elapsed=165936.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 267.698975/1288, current 267.864929/1298: elapsed=165936.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.715576
last_received_ts = 267.865082
last_seq = 1289
current_ts = 267.881531
current_received_ts = 268.030975
current_seq = 1299
count = 36
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.715576/1289, current 267.881531/1299: elapsed=165935.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 267.715576/1289, current 267.881531/1299: elapsed=165935.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 267.864929
last_received_ts = 267.864960
last_seq = 1298
current_ts = 268.030884
current_received_ts = 268.030884
current_seq = 1308
count = 36
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 267.864929/1298, current 268.030884/1308: elapsed=165976.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 267.864929/1298, current 268.030884/1308: elapsed=165976.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 267.881531
last_received_ts = 268.030975
last_seq = 1299
current_ts = 268.047485
current_received_ts = 268.196960
current_seq = 1309
count = 37
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 267.881531/1299, current 268.047485/1309: elapsed=165963.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 267.881531/1299, current 268.047485/1309: elapsed=165963.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.030884
last_received_ts = 268.030884
last_seq = 1308
current_ts = 268.196869
current_received_ts = 268.196899
current_seq = 1318
count = 37
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.030884/1308, current 268.196869/1318: elapsed=165965.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.030884/1308, current 268.196869/1318: elapsed=165965.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.047485
last_received_ts = 268.196960
last_seq = 1309
current_ts = 268.213440
current_received_ts = 268.362915
current_seq = 1319
count = 38
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.047485/1309, current 268.213440/1319: elapsed=165963.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.047485/1309, current 268.213440/1319: elapsed=165963.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.196869
last_received_ts = 268.196899
last_seq = 1318
current_ts = 268.362823
current_received_ts = 268.362854
current_seq = 1328
count = 38
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.196869/1318, current 268.362823/1328: elapsed=165952.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.196869/1318, current 268.362823/1328: elapsed=165952.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.213440
last_received_ts = 268.362915
last_seq = 1319
current_ts = 268.379425
current_received_ts = 268.528870
current_seq = 1329
count = 39
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.213440/1319, current 268.379425/1329: elapsed=165970.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.213440/1319, current 268.379425/1329: elapsed=165970.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.362823
last_received_ts = 268.362854
last_seq = 1328
current_ts = 268.528778
current_received_ts = 268.528809
current_seq = 1338
count = 39
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.362823/1328, current 268.528778/1338: elapsed=165967.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.362823/1328, current 268.528778/1338: elapsed=165967.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.379425
last_received_ts = 268.528870
last_seq = 1329
current_ts = 268.545380
current_received_ts = 268.694855
current_seq = 1339
count = 40
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.379425/1329, current 268.545380/1339: elapsed=165967.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.379425/1329, current 268.545380/1339: elapsed=165967.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.528778
last_received_ts = 268.528809
last_seq = 1338
current_ts = 268.694733
current_received_ts = 268.694794
current_seq = 1348
count = 40
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.528778/1338, current 268.694733/1348: elapsed=165964.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.528778/1338, current 268.694733/1348: elapsed=165964.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.545380
last_received_ts = 268.694855
last_seq = 1339
current_ts = 268.711334
current_received_ts = 268.860931
current_seq = 1349
count = 41
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.545380/1339, current 268.711334/1349: elapsed=165964.0us expected=165108.1us +- 825.5us, error 0.5%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.545380/1339, current 268.711334/1349: elapsed=165964.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.694733
last_received_ts = 268.694794
last_seq = 1348
current_ts = 268.860748
current_received_ts = 268.860870
current_seq = 1358
count = 41
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.694733/1348, current 268.860748/1358: elapsed=166019.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.694733/1348, current 268.860748/1358: elapsed=166019.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.711334
last_received_ts = 268.860931
last_seq = 1349
current_ts = 268.877380
current_received_ts = 269.026978
current_seq = 1359
count = 42
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.711334/1349, current 268.877380/1359: elapsed=166031.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.711334/1349, current 268.877380/1359: elapsed=166031.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 268.860748
last_received_ts = 268.860870
last_seq = 1358
current_ts = 269.026825
current_received_ts = 269.026917
current_seq = 1368
count = 42
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 268.860748/1358, current 269.026825/1368: elapsed=166049.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 268.860748/1358, current 269.026825/1368: elapsed=166049.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 268.877380
last_received_ts = 269.026978
last_seq = 1359
current_ts = 269.043396
current_received_ts = 269.193054
current_seq = 1369
count = 43
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 268.877380/1359, current 269.043396/1369: elapsed=166030.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 268.877380/1359, current 269.043396/1369: elapsed=166030.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 269.026825
last_received_ts = 269.026917
last_seq = 1368
current_ts = 269.192841
current_received_ts = 269.192932
current_seq = 1378
count = 43
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 269.026825/1368, current 269.192841/1378: elapsed=166044.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 269.026825/1368, current 269.192841/1378: elapsed=166044.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 269.043396
last_received_ts = 269.193054
last_seq = 1369
current_ts = 269.209473
current_received_ts = 269.359100
current_seq = 1379
count = 44
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 269.043396/1369, current 269.209473/1379: elapsed=166055.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 269.043396/1369, current 269.209473/1379: elapsed=166055.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 269.192841
last_received_ts = 269.192932
last_seq = 1378
current_ts = 269.358887
current_received_ts = 269.358978
current_seq = 1388
count = 44
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 269.192841/1378, current 269.358887/1388: elapsed=166040.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 269.192841/1378, current 269.358887/1388: elapsed=166040.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = flip
last_ts = 269.209473
last_received_ts = 269.359100
last_seq = 1379
current_ts = 269.375488
current_received_ts = 269.525116
current_seq = 1389
count = 45
seq_step = 1
(kms_flip:7086) DEBUG: flip ts/seq: last 269.209473/1379, current 269.375488/1389: elapsed=166032.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent flip ts/seq: last 269.209473/1379, current 269.375488/1389: elapsed=166032.0us expected=165108.1us
(kms_flip:7086) DEBUG: name = vblank
last_ts = 269.358887
last_received_ts = 269.358978
last_seq = 1388
current_ts = 269.524933
current_received_ts = 269.525055
current_seq = 1398
count = 45
seq_step = 10
(kms_flip:7086) DEBUG: vblank ts/seq: last 269.358887/1388, current 269.524933/1398: elapsed=166039.0us expected=165108.1us +- 825.5us, error 0.6%
(kms_flip:7086) DEBUG: inconsistent vblank ts/seq: last 269.358887/1388, current 269.524933/1398: elapsed=166039.0us expected=165108.1us
(kms_flip:7086) INFO: Event flip: expected 461, counted 460, passrate = 78.26%, encoder type 2
(kms_flip:7086) DEBUG: dropped frames, expected 461, counted 460, passrate = 78.26%, encoder type 2
(kms_flip:7086) INFO: Event vblank: expected 461, counted 460, passrate = 76.09%, encoder type 2
(kms_flip:7086) DEBUG: dropped frames, expected 461, counted 460, passrate = 76.09%, encoder type 2
(kms_flip:7086) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7086) CRITICAL: Failed assertion: !retried
(kms_flip:7086) igt_core-INFO: Stack trace:
(kms_flip:7086) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:7086) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:7086) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:7086) igt_core-INFO:   #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
(kms_flip:7086) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:7086) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:7086) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:7086) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A3: FAIL (20.932s)
Dmesg
<6> [266.030908] [IGT] kms_flip: starting dynamic subtest B-HDMI-A3
<7> [266.032979] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:556]
<7> [266.034011] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [266.095690] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [266.095884] 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> [266.096189] 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> [266.096461] 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> [266.096746] 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> [266.096986] 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> [266.097218] 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> [266.097447] 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> [266.097671] 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> [266.097887] 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> [266.098101] 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> [266.098306] 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> [266.098508] 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> [266.098726] 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> [266.098927] 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> [266.099116] 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> [266.099301] 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> [266.099480] 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> [266.099662] 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> [266.099834] 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> [266.100006] 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> [266.100178] 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> [266.100342] 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> [266.100504] 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> [266.100670] 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> [266.100827] 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> [266.100984] 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> [266.101142] 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> [266.101291] 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> [266.101441] 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> [266.101589] 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> [266.101751] 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> [266.101907] 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> [266.102051] 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> [266.102195] 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> [266.102330] 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> [266.102463] 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> [266.102598] 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> [266.102744] 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> [266.102874] 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> [266.103003] 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> [266.103129] 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> [266.103252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in avi infoframe
<7> [266.103373] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.103493] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [266.103495] xe 0000:03:00.0: colorspace: RGB
<7> [266.103497] xe 0000:03:00.0: scan mode: Underscan
<7> [266.103498] xe 0000:03:00.0: colorimetry: No Data
<7> [266.103500] xe 0000:03:00.0: picture aspect: No Data
<7> [266.103501] xe 0000:03:00.0: active aspect: Same as Picture
<7> [266.103503] xe 0000:03:00.0: itc: No Data
<7> [266.103504] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [266.103506] xe 0000:03:00.0: quantization range: Full
<7> [266.103507] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [266.103509] xe 0000:03:00.0: video code: 0
<7> [266.103510] xe 0000:03:00.0: ycc quantization range: Full
<7> [266.103512] xe 0000:03:00.0: hdmi content type: Graphics
<7> [266.103513] xe 0000:03:00.0: pixel repeat: 0
<7> [266.103514] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [266.103516] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.103641] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in spd infoframe
<7> [266.103759] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.103871] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [266.103873] xe 0000:03:00.0: vendor: Intel
<7> [266.103875] xe 0000:03:00.0: product: Discrete gfx
<7> [266.103877] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [266.103878] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.103990] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hdmi infoframe
<7> [266.104099] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.104209] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [266.104214] xe 0000:03:00.0: empty frame
<7> [266.104215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.104322] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [266.104430] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:150:pipe A] releasing TC PLL 3
<7> [266.104573] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [266.104679] 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> [266.104792] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [266.104893] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [266.105027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: no [modeset]
<7> [266.105141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [266.105249] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [266.105350] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [266.105446] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [266.105542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [266.105644] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [266.106320] 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> [266.123706] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [266.124092] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [266.145743] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x1, on? 1) for [CRTC:150:pipe A]
<7> [266.148166] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [266.148961] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [266.149050] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [266.149418] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<6> [266.149957] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [266.149915] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [266.150350] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [266.150811] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [266.151234] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [266.151665] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [266.152081] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [266.152494] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [266.152935] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [266.153344] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [266.153991] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [266.154350] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [266.154729] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [266.155056] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [266.155380] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [266.155717] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [266.156257] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [266.156769] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [266.157239] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [266.157850] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [266.158366] 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> [266.158884] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [266.159890] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [266.160306] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [266.160500] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [266.160707] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [266.160746] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [266.160866] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [266.161102] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [266.161327] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:268:pipe B]
<7> [266.161543] 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> [266.161775] 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> [266.161976] 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> [266.162166] 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> [266.162342] 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> [266.162512] 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> [266.162697] 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> [266.162855] 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> [266.163008] 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> [266.163155] 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> [266.163298] 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> [266.163439] 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> [266.163579] 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> [266.163646] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [266.163730] 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> [266.163863] 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 1083)
<7> [266.163992] 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 1088)
<7> [266.164119] 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> [266.164243] 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> [266.164365] 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> [266.164485] 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> [266.164603] 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> [266.164735] 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> [266.164850] 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> [266.164958] 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> [266.165068] 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> [266.165175] 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> [266.165280] 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 1083)
<7> [266.165377] 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 1088)
<7> [266.165479] 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> [266.165579] 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> [266.165691] 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> [266.165792] 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> [266.165887] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [266.165983] 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> [266.166077] 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> [266.166168] 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> [266.166258] 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> [266.166349] 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> [266.166440] 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> [266.166531] 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> [266.166621] 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> [266.166725] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [266.166816] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.166906] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.166996] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [266.166998] xe 0000:03:00.0: colorspace: RGB
<7> [266.166999] xe 0000:03:00.0: scan mode: Underscan
<7> [266.167000] xe 0000:03:00.0: colorimetry: No Data
<7> [266.167001] xe 0000:03:00.0: picture aspect: No Data
<7> [266.167002] xe 0000:03:00.0: active aspect: Same as Picture
<7> [266.167003] xe 0000:03:00.0: itc: No Data
<7> [266.167004] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [266.167005] xe 0000:03:00.0: quantization range: Full
<7> [266.167006] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [266.167008] xe 0000:03:00.0: video code: 0
<7> [266.167009] xe 0000:03:00.0: ycc quantization range: Full
<7> [266.167010] xe 0000:03:00.0: hdmi content type: Graphics
<7> [266.167011] xe 0000:03:00.0: pixel repeat: 0
<7> [266.167012] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [266.167013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [266.167104] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.167194] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.167283] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [266.167285] xe 0000:03:00.0: vendor: Intel
<7> [266.167286] xe 0000:03:00.0: product: Discrete gfx
<7> [266.167287] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [266.167288] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [266.167378] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [266.167468] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [266.167557] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [266.167559] xe 0000:03:00.0: empty frame
<7> [266.167560] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [266.167685] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [266.167767] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [266.167864] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [266.167940] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [266.168014] 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> [266.168089] 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 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [266.168162] 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 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [266.168234] 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 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [266.168317] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 594000 num active planes 1
<7> [266.168422] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [266.168523] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [266.168619] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [266.168726] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [266.168846] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:268:pipe B] allocated TC PLL 3
<7> [266.168944] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:268:pipe B] reserving TC PLL 3
<7> [266.169039] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: yes [modeset]
<7> [266.169140] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [266.169239] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [266.169334] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [266.169430] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [266.169525] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [266.169618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [266.169774] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [266.169868] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [266.169960] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [266.169962] xe 0000:03:00.0: colorspace: RGB
<7> [266.169963] xe 0000:03:00.0: scan mode: Underscan
<7> [266.169965] xe 0000:03:00.0: colorimetry: No Data
<7> [266.169966] xe 0000:03:00.0: picture aspect: No Data
<7> [266.169967] xe 0000:03:00.0: active aspect: Same as Picture
<7> [266.169968] xe 0000:03:00.0: itc: No Data
<7> [266.169969] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [266.169970] xe 0000:03:00.0: quantization range: Full
<7> [266.169971] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [266.169972] xe 0000:03:00.0: video code: 0
<7> [266.169974] xe 0000:03:00.0: ycc quantization range: Full
<7> [266.169975] xe 0000:03:00.0: hdmi content type: Graphics
<7> [266.169976] xe 0000:03:00.0: pixel repeat: 0
<7> [266.169977] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [266.169978] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [266.169979] xe 0000:03:00.0: vendor: Intel
<7> [266.169981] xe 0000:03:00.0: product: Discrete gfx
<7> [266.169982] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [266.169983] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [266.169984] xe 0000:03:00.0: empty frame
<7> [266.169986] 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> [266.170079] 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> [266.170171] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [266.170263] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [266.170355] 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> [266.170449] 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> [266.170542] 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> [266.170685] 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> [266.170780] 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> [266.170873] 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> [266.170965] 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> [266.171057] 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> [266.171150] 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> [266.171242] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [266.171332] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [266.171423] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [266.171516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [266.171607] 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> [266.171710] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [266.171801] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [266.171890] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [266.171982] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [266.172072] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [266.172163] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [266.172254] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [266.172349] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [266.172439] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [266.172530] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [266.172621] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [266.172724] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [266.172815] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [266.172905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [266.172995] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [266.173086] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [266.173176] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [266.173267] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [266.173357] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.173448] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.173538] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.173631] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [266.173721] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [266.173811] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.173901] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.173988] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [266.174077] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [266.174167] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [266.174257] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [266.174349] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [266.174438] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [266.174528] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [266.174618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [266.174722] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [266.174813] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [266.174900] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [266.175513] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [266.175656] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [266.175798] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [266.175908] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [266.175999] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [266.176087] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [266.176171] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [266.176254] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [266.176337] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [266.176420] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [266.176503] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [266.176584] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [266.176675] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [266.176756] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [266.176835] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [266.176916] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [266.176995] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [266.177074] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [266.177152] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [266.177240] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [266.177404] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [266.177484] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [266.177585] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:268:pipe B]
<7> [266.177687] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [266.180182] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [266.198995] 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], 32 bytes ELD
<7> [266.211738] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [266.215227] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [266.215430] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [266.215766] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [266.216164] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [267.063684] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x25252222
<7> [267.063871] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x24252423
<7> [277.242547] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [277.243564] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [277.244082] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [277.244831] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [277.245468] 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> [277.246028] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [277.246623] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [277.247177] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [277.247707] 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 1920, found 0)
<7> [277.248257] 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 2200, found 0)
<7> [277.248780] 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 1920, found 0)
<7> [277.249301] 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 2200, found 0)
<7> [277.249394] 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 2008, found 0)
<7> [277.249486] 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 2052, found 0)
<7> [277.249576] 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 1080, found 0)
<7> [277.249666] 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 1080, found 0)
<7> [277.249755] 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 1083, found 0)
<7> [277.249843] 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 1088, found 0)
<7> [277.249931] 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 1125, found 0)
<7> [277.250020] 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 1125, found 0)
<7> [277.250108] 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 1920, found 0)
<7> [277.250204] 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 2200, found 0)
<7> [277.250295] 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 1920, found 0)
<7> [277.250385] 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 2200, found 0)
<7> [277.250475] 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 2008, found 0)
<7> [277.250566] 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 2052, found 0)
<7> [277.250657] 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 1080, found 0)
<7> [277.250747] 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 1080, found 0)
<7> [277.250837] 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 1083, found 0)
<7> [277.250928] 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 1088, found 0)
<7> [277.251019] 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 1125, found 0)
<7> [277.251108] 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 1125, found 0)
<7> [277.251206] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [277.251303] 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 1, found 0)
<7> [277.251394] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [277.251483] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [277.251572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [277.251662] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [277.251751] 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 148500, found 0)
<7> [277.251840] 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 148500, found 0)
<7> [277.251927] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [277.252017] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [277.252107] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [277.252212] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [277.252302] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.252392] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [277.252393] xe 0000:03:00.0: colorspace: RGB
<7> [277.252395] xe 0000:03:00.0: scan mode: Underscan
<7> [277.252396] xe 0000:03:00.0: colorimetry: No Data
<7> [277.252397] xe 0000:03:00.0: picture aspect: No Data
<7> [277.252398] xe 0000:03:00.0: active aspect: Same as Picture
<7> [277.252399] xe 0000:03:00.0: itc: No Data
<7> [277.252400] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [277.252401] xe 0000:03:00.0: quantization range: Full
<7> [277.252402] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [277.252403] xe 0000:03:00.0: video code: 0
<7> [277.252404] xe 0000:03:00.0: ycc quantization range: Full
<7> [277.252405] xe 0000:03:00.0: hdmi content type: Graphics
<7> [277.252406] xe 0000:03:00.0: pixel repeat: 0
<7> [277.252407] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [277.252408] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.252497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [277.252586] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.252675] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [277.252676] xe 0000:03:00.0: vendor: Intel
<7> [277.252677] xe 0000:03:00.0: product: Discrete gfx
<7> [277.252678] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [277.252679] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.252768] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [277.252855] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.252950] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [277.252951] xe 0000:03:00.0: empty frame
<7> [277.252952] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.253046] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [277.253140] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing TC PLL 3
<7> [277.253278] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [277.253360] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [277.253456] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 4037) -> ( 0 - 0), size 4037 -> 0
<7> [277.253531] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [277.253606] 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> [277.253677] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [277.253748] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [277.253819] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] 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> [277.253904] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 0 num active planes 0
<7> [277.254005] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [277.254102] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [277.254201] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [277.254298] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [277.254408] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [277.254521] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [277.254618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [277.254712] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [277.254806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [277.254898] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [277.254990] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [277.255082] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [277.255595] 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:268:pipe B]
<7> [277.282750] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [277.283928] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [277.305995] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [277.308093] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [277.308586] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [277.309500] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [277.310056] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [277.310540] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [277.311038] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [277.311496] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [277.311975] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [277.312431] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [277.312894] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [277.313336] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [277.313775] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [277.314263] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [277.314933] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [277.315369] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [277.315765] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [277.316188] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [277.316569] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [277.316947] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [277.317544] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [277.318072] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [277.318580] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [277.319420] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [277.319972] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [277.320527] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [277.321395] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [277.321827] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [277.322419] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [277.322458] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [277.322582] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [277.322820] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [277.323075] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:268:pipe B]
<7> [277.323301] 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> [277.323512] 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> [277.323708] 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> [277.323864] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [277.323904] 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> [277.324090] 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> [277.324263] 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> [277.324429] 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> [277.324590] 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> [277.324744] 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> [277.324898] 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> [277.325067] 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> [277.325214] 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> [277.325355] 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> [277.325493] 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> [277.325650] 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 1083)
<7> [277.325781] 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 1088)
<7> [277.325914] 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> [277.326059] 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> [277.326186] 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> [277.326305] 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> [277.326422] 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> [277.326533] 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> [277.326644] 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> [277.326754] 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> [277.326863] 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> [277.326978] 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> [277.327087] 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 1083)
<7> [277.327197] 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 1088)
<7> [277.327300] 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> [277.327398] 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> [277.327497] 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> [277.327596] 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> [277.327694] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [277.327790] 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> [277.327886] 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> [277.327992] 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> [277.328091] 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> [277.328188] 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> [277.328285] 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> [277.328383] 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> [277.328484] 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> [277.328588] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [277.328688] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.328787] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.328886] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [277.328887] xe 0000:03:00.0: colorspace: RGB
<7> [277.328888] xe 0000:03:00.0: scan mode: Underscan
<7> [277.328889] xe 0000:03:00.0: colorimetry: No Data
<7> [277.328891] xe 0000:03:00.0: picture aspect: No Data
<7> [277.328892] xe 0000:03:00.0: active aspect: Same as Picture
<7> [277.328893] xe 0000:03:00.0: itc: No Data
<7> [277.328894] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [277.328895] xe 0000:03:00.0: quantization range: Full
<7> [277.328896] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [277.328897] xe 0000:03:00.0: video code: 0
<7> [277.328898] xe 0000:03:00.0: ycc quantization range: Full
<7> [277.328899] xe 0000:03:00.0: hdmi content type: Graphics
<7> [277.328900] xe 0000:03:00.0: pixel repeat: 0
<7> [277.328901] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [277.328903] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [277.329012] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.329110] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.329206] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [277.329208] xe 0000:03:00.0: vendor: Intel
<7> [277.329209] xe 0000:03:00.0: product: Discrete gfx
<7> [277.329210] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [277.329211] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [277.329308] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [277.329407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [277.329505] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [277.329506] xe 0000:03:00.0: empty frame
<7> [277.329507] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [277.329633] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [277.329720] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [277.329823] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [277.329908] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [277.329996] 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> [277.330075] 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 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [277.330154] 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 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [277.330229] 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 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [277.330315] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 594000 num active planes 1
<7> [277.330425] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [277.330532] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [277.330637] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [277.330742] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [277.330855] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [277.330975] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:268:pipe B] allocated TC PLL 3
<7> [277.331079] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:268:pipe B] reserving TC PLL 3
<7> [277.331180] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: yes [modeset]
<7> [277.331288] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [277.331394] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [277.331499] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [277.331602] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [277.331704] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [277.331802] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [277.331904] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [277.332014] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [277.332115] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [277.332117] xe 0000:03:00.0: colorspace: RGB
<7> [277.332118] xe 0000:03:00.0: scan mode: Underscan
<7> [277.332119] xe 0000:03:00.0: colorimetry: No Data
<7> [277.332120] xe 0000:03:00.0: picture aspect: No Data
<7> [277.332121] xe 0000:03:00.0: active aspect: Same as Picture
<7> [277.332122] xe 0000:03:00.0: itc: No Data
<7> [277.332123] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [277.332124] xe 0000:03:00.0: quantization range: Full
<7> [277.332125] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [277.332126] xe 0000:03:00.0: video code: 0
<7> [277.332127] xe 0000:03:00.0: ycc quantization range: Full
<7> [277.332129] xe 0000:03:00.0: hdmi content type: Graphics
<7> [277.332130] xe 0000:03:00.0: pixel repeat: 0
<7> [277.332131] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [277.332132] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [277.332133] xe 0000:03:00.0: vendor: Intel
<7> [277.332135] xe 0000:03:00.0: product: Discrete gfx
<7> [277.332138] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [277.332140] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [277.332141] xe 0000:03:00.0: empty frame
<7> [277.332142] 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> [277.332243] 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> [277.332342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [277.332439] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [277.332537] 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> [277.332636] 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> [277.332735] 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> [277.332834] 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> [277.332933] 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> [277.333039] 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> [277.333140] 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> [277.333239] 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> [277.333339] 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> [277.333439] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [277.333535] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [277.333631] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [277.333728] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [277.333826] 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> [277.333926] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [277.334077] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [277.334175] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [277.334273] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [277.334371] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [277.334469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [277.334566] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [277.334664] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [277.334760] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [277.334856] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [277.334953] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [277.335059] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [277.335156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [277.335254] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [277.335351] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [277.335447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [277.335544] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [277.335641] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [277.335737] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.335832] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.335928] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.336027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [277.336126] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [277.336222] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.336317] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.336413] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.336509] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [277.336606] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [277.336703] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [277.336800] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [277.336898] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [277.336995] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [277.337100] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [277.337196] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [277.337294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [277.337392] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [277.338001] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [277.338162] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [277.338519] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [277.338637] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [277.338735] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [277.338827] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [277.338920] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [277.339010] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [277.339109] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [277.339198] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [277.339285] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [277.339370] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [277.339454] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [277.339543] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [277.339630] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [277.339715] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [277.339801] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [277.339884] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [277.339968] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [277.340268] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [277.340440] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [277.340728] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [277.340835] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:268:pipe B]
<7> [277.340935] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [277.343633] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [277.362502] 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], 32 bytes ELD
<7> [277.376176] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [277.378740] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [277.379032] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [277.379674] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [277.380158] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [282.097722] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x25252121
<7> [282.098463] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x24252122
<6> [286.964189] [IGT] kms_flip: finished subtest B-HDMI-A3, FAIL
Created at 2026-03-19 05:57:23