Results for igt@kms_flip@plain-flip-fb-recreate@a-dp2

Machine description: shard-dg2-432

Result: Fail

i915_display_info19 igt_runner19 results19.json results19-xe-load.json i915_display_info_post_exec19 boot19 dmesg19

DetailValue
Duration 4.43 seconds
Hostname 
shard-dg2-432
Igt-Version 
IGT-Version: 1.30-g43a53635d (x86_64) (Linux: 6.14.0-rc4-xe+ x86_64)
Out 
Starting dynamic subtest: A-DP2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16674.0us +- 3.882us accuracy 0.07%
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #7 [_start+0x25]
Dynamic subtest A-DP2: FAIL (4.435s)
Err 
Starting dynamic subtest: A-DP2
(kms_flip:31770) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:31770) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-DP2 failed.
**** DEBUG ****
(kms_flip:31770) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:31770) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:31770) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:31770) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:31770) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:31770) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:31770) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d50640, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d50640, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d79190, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d79190, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d50640, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d50640, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d79190, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) intel_allocator_simple-DEBUG: <ial: 0x59ca71d79190, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:31770) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:31770) DEBUG: No stale events found
(kms_flip:31770) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:31770) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:31770) INFO: Expected frametime: 16667us; measured 16674.0us +- 3.882us accuracy 0.07%
(kms_flip:31770) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 459.112488
current_received_ts = 459.112000
current_seq = 19491
count = 0
seq_step = 1
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.112488
last_received_ts = 459.112000
last_seq = 19491
current_ts = 459.129181
current_received_ts = 459.128662
current_seq = 19492
count = 1
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.112488/19491, current 459.129181/19492: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.129181
last_received_ts = 459.128662
last_seq = 19492
current_ts = 459.145844
current_received_ts = 459.145355
current_seq = 19493
count = 2
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.129181/19492, current 459.145844/19493: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.145844
last_received_ts = 459.145355
last_seq = 19493
current_ts = 459.162506
current_received_ts = 459.161987
current_seq = 19494
count = 3
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.145844/19493, current 459.162506/19494: elapsed=16672.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.162506
last_received_ts = 459.161987
last_seq = 19494
current_ts = 459.179199
current_received_ts = 459.178589
current_seq = 19495
count = 4
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.162506/19494, current 459.179199/19495: elapsed=16668.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.179199
last_received_ts = 459.178589
last_seq = 19495
current_ts = 459.195862
current_received_ts = 459.195282
current_seq = 19496
count = 5
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.179199/19495, current 459.195862/19496: elapsed=16673.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.195862
last_received_ts = 459.195282
last_seq = 19496
current_ts = 459.212524
current_received_ts = 459.211945
current_seq = 19497
count = 6
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.195862/19496, current 459.212524/19497: elapsed=16681.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.212524
last_received_ts = 459.211945
last_seq = 19497
current_ts = 459.229218
current_received_ts = 459.228607
current_seq = 19498
count = 7
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.212524/19497, current 459.229218/19498: elapsed=16669.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.229218
last_received_ts = 459.228607
last_seq = 19498
current_ts = 459.245880
current_received_ts = 459.245270
current_seq = 19499
count = 8
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.229218/19498, current 459.245880/19499: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.245880
last_received_ts = 459.245270
last_seq = 19499
current_ts = 459.262543
current_received_ts = 459.261963
current_seq = 19500
count = 9
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.245880/19499, current 459.262543/19500: elapsed=16673.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.262543
last_received_ts = 459.261963
last_seq = 19500
current_ts = 459.279236
current_received_ts = 459.278687
current_seq = 19501
count = 10
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.262543/19500, current 459.279236/19501: elapsed=16673.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.279236
last_received_ts = 459.278687
last_seq = 19501
current_ts = 459.295898
current_received_ts = 459.295319
current_seq = 19502
count = 11
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.279236/19501, current 459.295898/19502: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.295898
last_received_ts = 459.295319
last_seq = 19502
current_ts = 459.312592
current_received_ts = 459.312073
current_seq = 19503
count = 12
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.295898/19502, current 459.312592/19503: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.312592
last_received_ts = 459.312073
last_seq = 19503
current_ts = 459.329254
current_received_ts = 459.328674
current_seq = 19504
count = 13
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.312592/19503, current 459.329254/19504: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.329254
last_received_ts = 459.328674
last_seq = 19504
current_ts = 459.345947
current_received_ts = 459.345398
current_seq = 19505
count = 14
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.329254/19504, current 459.345947/19505: elapsed=16679.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.345947
last_received_ts = 459.345398
last_seq = 19505
current_ts = 459.362610
current_received_ts = 459.362091
current_seq = 19506
count = 15
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.345947/19505, current 459.362610/19506: elapsed=16669.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.362610
last_received_ts = 459.362091
last_seq = 19506
current_ts = 459.379272
current_received_ts = 459.378693
current_seq = 19507
count = 16
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.362610/19506, current 459.379272/19507: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.379272
last_received_ts = 459.378693
last_seq = 19507
current_ts = 459.395935
current_received_ts = 459.395355
current_seq = 19508
count = 17
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.379272/19507, current 459.395935/19508: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.395935
last_received_ts = 459.395355
last_seq = 19508
current_ts = 459.412628
current_received_ts = 459.412048
current_seq = 19509
count = 18
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.395935/19508, current 459.412628/19509: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.412628
last_received_ts = 459.412048
last_seq = 19509
current_ts = 459.429291
current_received_ts = 459.428711
current_seq = 19510
count = 19
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.412628/19509, current 459.429291/19510: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.429291
last_received_ts = 459.428711
last_seq = 19510
current_ts = 459.445984
current_received_ts = 459.445374
current_seq = 19511
count = 20
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.429291/19510, current 459.445984/19511: elapsed=16673.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.445984
last_received_ts = 459.445374
last_seq = 19511
current_ts = 459.462646
current_received_ts = 459.462067
current_seq = 19512
count = 21
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.445984/19511, current 459.462646/19512: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.462646
last_received_ts = 459.462067
last_seq = 19512
current_ts = 459.479309
current_received_ts = 459.478729
current_seq = 19513
count = 22
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.462646/19512, current 459.479309/19513: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.479309
last_received_ts = 459.478729
last_seq = 19513
current_ts = 459.496002
current_received_ts = 459.495392
current_seq = 19514
count = 23
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.479309/19513, current 459.496002/19514: elapsed=16673.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.496002
last_received_ts = 459.495392
last_seq = 19514
current_ts = 459.512665
current_received_ts = 459.512085
current_seq = 19515
count = 24
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.496002/19514, current 459.512665/19515: elapsed=16682.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.512665
last_received_ts = 459.512085
last_seq = 19515
current_ts = 459.529327
current_received_ts = 459.528748
current_seq = 19516
count = 25
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.512665/19515, current 459.529327/19516: elapsed=16667.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.529327
last_received_ts = 459.528748
last_seq = 19516
current_ts = 459.546021
current_received_ts = 459.545410
current_seq = 19517
count = 26
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.529327/19516, current 459.546021/19517: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.546021
last_received_ts = 459.545410
last_seq = 19517
current_ts = 459.562683
current_received_ts = 459.562073
current_seq = 19518
count = 27
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.546021/19517, current 459.562683/19518: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.562683
last_received_ts = 459.562073
last_seq = 19518
current_ts = 459.579376
current_received_ts = 459.578766
current_seq = 19519
count = 28
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.562683/19518, current 459.579376/19519: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.579376
last_received_ts = 459.578766
last_seq = 19519
current_ts = 459.596039
current_received_ts = 459.595459
current_seq = 19520
count = 29
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.579376/19519, current 459.596039/19520: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.596039
last_received_ts = 459.595459
last_seq = 19520
current_ts = 459.612701
current_received_ts = 459.612183
current_seq = 19521
count = 30
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.596039/19520, current 459.612701/19521: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.612701
last_received_ts = 459.612183
last_seq = 19521
current_ts = 459.629395
current_received_ts = 459.628784
current_seq = 19522
count = 31
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.612701/19521, current 459.629395/19522: elapsed=16675.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.629395
last_received_ts = 459.628784
last_seq = 19522
current_ts = 459.646057
current_received_ts = 459.645477
current_seq = 19523
count = 32
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.629395/19522, current 459.646057/19523: elapsed=16680.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.646057
last_received_ts = 459.645477
last_seq = 19523
current_ts = 459.662750
current_received_ts = 459.662140
current_seq = 19524
count = 33
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.646057/19523, current 459.662750/19524: elapsed=16668.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.662750
last_received_ts = 459.662140
last_seq = 19524
current_ts = 459.679413
current_received_ts = 459.678802
current_seq = 19525
count = 34
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.662750/19524, current 459.679413/19525: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.679413
last_received_ts = 459.678802
last_seq = 19525
current_ts = 459.696075
current_received_ts = 459.695587
current_seq = 19526
count = 35
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.679413/19525, current 459.696075/19526: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.696075
last_received_ts = 459.695587
last_seq = 19526
current_ts = 459.712769
current_received_ts = 459.712311
current_seq = 19527
count = 36
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.696075/19526, current 459.712769/19527: elapsed=16677.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.712769
last_received_ts = 459.712311
last_seq = 19527
current_ts = 459.729431
current_received_ts = 459.728912
current_seq = 19528
count = 37
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.712769/19527, current 459.729431/19528: elapsed=16674.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.729431
last_received_ts = 459.728912
last_seq = 19528
current_ts = 459.746124
current_received_ts = 459.745575
current_seq = 19529
count = 38
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.729431/19528, current 459.746124/19529: elapsed=16680.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.746124
last_received_ts = 459.745575
last_seq = 19529
current_ts = 459.762787
current_received_ts = 459.762238
current_seq = 19530
count = 39
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.746124/19529, current 459.762787/19530: elapsed=16677.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.762787
last_received_ts = 459.762238
last_seq = 19530
current_ts = 459.779449
current_received_ts = 459.778900
current_seq = 19531
count = 40
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.762787/19530, current 459.779449/19531: elapsed=16672.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.779449
last_received_ts = 459.778900
last_seq = 19531
current_ts = 459.796143
current_received_ts = 459.795624
current_seq = 19532
count = 41
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.779449/19531, current 459.796143/19532: elapsed=16676.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.796143
last_received_ts = 459.795624
last_seq = 19532
current_ts = 459.812805
current_received_ts = 459.812286
current_seq = 19533
count = 42
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.796143/19532, current 459.812805/19533: elapsed=16669.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.812805
last_received_ts = 459.812286
last_seq = 19533
current_ts = 459.829498
current_received_ts = 459.828949
current_seq = 19534
count = 43
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.812805/19533, current 459.829498/19534: elapsed=16676.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.829498
last_received_ts = 459.828949
last_seq = 19534
current_ts = 459.846161
current_received_ts = 459.845612
current_seq = 19535
count = 44
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.829498/19534, current 459.846161/19535: elapsed=16667.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.846161
last_received_ts = 459.845612
last_seq = 19535
current_ts = 459.862823
current_received_ts = 459.862305
current_seq = 19536
count = 45
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.846161/19535, current 459.862823/19536: elapsed=16676.0us expected=16674.0us +- 83.4us, error 0.0%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.862823
last_received_ts = 459.862305
last_seq = 19536
current_ts = 459.879517
current_received_ts = 459.878967
current_seq = 19537
count = 46
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.862823/19536, current 459.879517/19537: elapsed=16683.0us expected=16674.0us +- 83.4us, error 0.1%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.879517
last_received_ts = 459.878967
last_seq = 19537
current_ts = 459.896179
current_received_ts = 459.895660
current_seq = 19538
count = 47
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.879517/19537, current 459.896179/19538: elapsed=16663.0us expected=16674.0us +- 83.4us, error 0.1%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.896179
last_received_ts = 459.895660
last_seq = 19538
current_ts = 459.912872
current_received_ts = 459.912384
current_seq = 19539
count = 48
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.896179/19538, current 459.912872/19539: elapsed=16685.0us expected=16674.0us +- 83.4us, error 0.1%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.912872
last_received_ts = 459.912384
last_seq = 19539
current_ts = 459.929535
current_received_ts = 459.928925
current_seq = 19540
count = 49
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.912872/19539, current 459.929535/19540: elapsed=16663.0us expected=16674.0us +- 83.4us, error 0.1%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.929535
last_received_ts = 459.928925
last_seq = 19540
current_ts = 459.946198
current_received_ts = 459.951202
current_seq = 19541
count = 50
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.929535/19540, current 459.946198/19541: elapsed=16685.0us expected=16674.0us +- 83.4us, error 0.1%
(kms_flip:31770) DEBUG: name = flip
last_ts = 459.946198
last_received_ts = 459.951202
last_seq = 19541
current_ts = 459.979553
current_received_ts = 459.978943
current_seq = 19543
count = 51
seq_step = 1
(kms_flip:31770) DEBUG: flip ts/seq: last 459.946198/19541, current 459.979553/19543: elapsed=33343.0us expected=33348.0us +- 166.7us, error 0.0%
(kms_flip:31770) DEBUG: unexpected flip seq 19543, expected 19542
(kms_flip:31770) DEBUG: expected 224, counted 51, encoder type 2
(kms_flip:31770) DEBUG: dropped frames, expected 224, counted 51, encoder type 2
(kms_flip:31770) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:31770) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:31770) igt_core-INFO: Stack trace:
(kms_flip:31770) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:31770) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:31770) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:31770) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:31770) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:31770) igt_core-INFO:   #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
(kms_flip:31770) igt_core-INFO:   #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
(kms_flip:31770) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-DP2: FAIL (4.435s)
Dmesg
<6> [457.955133] [IGT] kms_flip: starting dynamic subtest A-DP2
<7> [457.956775] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [457.957637] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.015765] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [458.015995] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [458.016160] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [458.016315] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [458.016458] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [458.016732] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [458.016961] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [458.017176] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [458.017397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [458.017595] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [458.017786] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [458.017977] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [458.018167] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [458.018369] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [458.018555] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [458.018734] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [458.018902] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [458.019073] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [458.019242] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [458.019464] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [458.019625] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [458.019785] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [458.019941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [458.020091] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [458.020240] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [458.020417] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [458.020566] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [458.020708] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [458.020851] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [458.020991] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [458.021134] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [458.021276] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [458.021449] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [458.021582] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [458.021713] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [458.021843] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [458.021973] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [458.022108] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [458.022310] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [458.022559] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [458.022738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [458.022919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [458.023094] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [458.023267] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [458.023466] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in avi infoframe
<7> [458.023630] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [458.023768] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [458.023770] xe 0000:03:00.0: colorspace: RGB
<7> [458.023772] xe 0000:03:00.0: scan mode: Underscan
<7> [458.023775] xe 0000:03:00.0: colorimetry: No Data
<7> [458.023778] xe 0000:03:00.0: picture aspect: 16:9
<7> [458.023781] xe 0000:03:00.0: active aspect: Same as Picture
<7> [458.023784] xe 0000:03:00.0: itc: No Data
<7> [458.023787] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [458.023790] xe 0000:03:00.0: quantization range: Default
<7> [458.023793] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [458.023794] xe 0000:03:00.0: video code: 16
<7> [458.023796] xe 0000:03:00.0: ycc quantization range: Full
<7> [458.023797] xe 0000:03:00.0: hdmi content type: Graphics
<7> [458.023799] xe 0000:03:00.0: pixel repeat: 0
<7> [458.023801] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [458.023803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [458.023925] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in spd infoframe
<7> [458.024036] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [458.024143] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [458.024145] xe 0000:03:00.0: vendor: Intel
<7> [458.024147] xe 0000:03:00.0: product: Discrete gfx
<7> [458.024148] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [458.024150] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [458.024256] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:238:pipe D] fastset requirement not met in hdmi infoframe
<7> [458.024391] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [458.024496] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [458.024498] xe 0000:03:00.0: empty frame
<7> [458.024499] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [458.024602] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:238:pipe D] fastset requirement not met, forcing full modeset
<7> [458.024735] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [458.024866] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:238:pipe D] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [458.024997] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:233:cursor D] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [458.025098] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [458.025196] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [458.025291] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [458.025398] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:238:pipe D] enable: no [modeset]
<7> [458.025496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:188:plane 1D] fb: [NOFB], visible: no
<7> [458.025584] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:plane 2D] fb: [NOFB], visible: no
<7> [458.025676] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:206:plane 3D] fb: [NOFB], visible: no
<7> [458.025766] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:215:plane 4D] fb: [NOFB], visible: no
<7> [458.025853] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:224:plane 5D] fb: [NOFB], visible: no
<7> [458.025934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:233:cursor D] fb: [NOFB], visible: no
<7> [458.026045] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:260:HDMI-A-2][ENCODER:259:DDI B/PHY B] Disable audio codec on [CRTC:238:pipe D]
<7> [458.050444] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port B
<7> [458.050840] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe D
<7> [458.070670] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling DDI_IO_B
<7> [458.071227] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DDI A/PHY A]
<7> [458.071688] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:242:DP-MST A]
<7> [458.072076] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:243:DP-MST B]
<7> [458.072489] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:244:DP-MST C]
<7> [458.072858] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:245:DP-MST D]
<7> [458.073214] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:259:DDI B/PHY B]
<7> [458.073609] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DDI C/PHY C]
<7> [458.073977] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST A]
<7> [458.074354] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST B]
<7> [458.074712] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:267:DP-MST C]
<7> [458.075080] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:268:DP-MST D]
<7> [458.075472] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:275:DDI D/PHY D]
<7> [458.075837] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:277:DP-MST A]
<7> [458.076194] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:278:DP-MST B]
<7> [458.076588] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:279:DP-MST C]
<7> [458.076941] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DP-MST D]
<7> [458.077252] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:260:HDMI-A-2]
<7> [458.077687] xe 0000:03:00.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 163200 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [458.078111] xe 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=408
<7> [458.078480] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [458.078892] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [458.079295] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [458.079724] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:238:pipe D]
<7> [458.080299] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [458.080390] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:264:DP-2]
<7> [458.080575] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:264:DP-2] Limiting display bpp to 24 (EDID bpp 48, max requested bpp 24, max platform bpp 36)
<7> [458.080885] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:263:DDI C/PHY C][CRTC:82:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 max link_bpp 24.0000
<7> [458.081166] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 162000 bpp input 24 compressed 0.0000 link rate required 445500 available 648000
<7> [458.081453] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [458.081722] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:263:DDI C/PHY C] [CRTC:82:pipe A]
<7> [458.081983] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [458.082230] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [458.082479] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [458.082698] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [458.082924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5767168/8388608 link 480597/524288)
<7> [458.083147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [458.083366] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [458.083570] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [458.083764] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [458.083951] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [458.084139] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [458.084334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [458.084515] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [458.084691] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [458.084861] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [458.085027] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [458.085184] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [458.085346] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [458.085504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [458.085659] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [458.085811] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [458.085961] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [458.086104] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [458.086249] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [458.086403] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [458.086542] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [458.086674] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [458.086806] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [458.086933] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [458.087059] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [458.087185] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [458.087317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [458.087434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [458.087551] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [458.087668] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [458.087784] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [458.087895] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [458.088008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [458.088121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 162000)
<7> [458.088238] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [458.088381] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [458.088503] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [458.088649] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [458.088738] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [458.088825] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [458.088911] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [458.088994] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [458.089076] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [458.089159] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 594000 num active planes 1
<7> [458.089261] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 50000 required 624
<7> [458.089370] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] No SAGV, using single QGV point mask 0x1
<7> [458.089481] xe 0000:03:00.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:32:plane 1A] min cdclk (74250 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [458.089577] xe 0000:03:00.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (11602 kHz) > old min cdclk (0 kHz)
<7> [458.089668] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [458.089756] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 204000 kHz, actual 204000 kHz
<7> [458.089843] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [458.089925] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [458.090012] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [458.090097] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [458.090181] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [458.090263] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [458.090352] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [458.090434] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [458.090510] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5767168, data_n: 8388608, link_m: 480597, link_n: 524288, tu: 64
<7> [458.090583] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [458.090657] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [458.090730] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [458.090803] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [458.090873] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [458.090943] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [458.091013] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [458.091083] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [458.091154] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [458.091224] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [458.091293] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [458.091380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [458.091458] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [458.091523] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [458.091588] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [458.091652] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [458.091717] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [458.091780] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [458.091845] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [458.091909] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [458.091974] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [458.092038] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [458.092102] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [458.092166] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x278d0, dpll_md: 0x18000000, fp0: 0x88228fe, fp1: 0x2a000440
<7> [458.092230] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [458.092294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [458.092365] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [458.092430] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092494] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092557] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092621] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [458.092684] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [458.092747] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092811] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092874] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [458.092937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [458.093000] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [FB:287] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [458.093065] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [458.093129] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [458.093195] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [458.093262] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [458.093332] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [458.093396] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [458.093463] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [458.093819] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [458.093930] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [458.094066] xe 0000:03:00.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 204000 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [458.094144] xe 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=510
<7> [458.094212] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DDI A/PHY A]
<7> [458.094277] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:242:DP-MST A]
<7> [458.094346] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:243:DP-MST B]
<7> [458.094410] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:244:DP-MST C]
<7> [458.094472] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:245:DP-MST D]
<7> [458.094531] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:259:DDI B/PHY B]
<7> [458.094590] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DDI C/PHY C]
<7> [458.094647] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST A]
<7> [458.094704] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST B]
<7> [458.094762] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:267:DP-MST C]
<7> [458.094821] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:268:DP-MST D]
<7> [458.094880] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:275:DDI D/PHY D]
<7> [458.094937] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:277:DP-MST A]
<7> [458.094994] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:278:DP-MST B]
<7> [458.095052] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:279:DP-MST C]
<7> [458.095109] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DP-MST D]
<7> [458.095167] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [458.095297] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_C
<7> [458.096391] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling DDI_IO_C
<7> [458.098255] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [458.105009] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [458.106148] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [458.107326] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Using LINK_BW_SET value 06
<7> [458.108769] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [458.108840] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Using DP training pattern TPS1
<7> [458.116319] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Clock recovery OK
<7> [458.116389] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Using DP training pattern TPS3
<7> [458.122398] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Channel EQ done. DP Training successful
<7> [458.122526] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C][DPRX] Link Training passed at link rate = 162000, lane count = 4
<7> [458.123681] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [458.124113] xe 0000:03:00.0: [drm:intel_fbc_update [xe]] reserved 4456448 bytes of contiguous stolen space for FBC, limit: 2
<7> [458.124223] xe 0000:03:00.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:32:plane 1A]
<7> [458.140691] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:264:DP-2][ENCODER:263:DDI C/PHY C] Enable audio codec on [CRTC:82:pipe A], 40 bytes ELD
<7> [458.157529] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:264:DP-2]
<7> [458.157993] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A]
<7> [458.457674] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.457767] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.474402] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.474483] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.491046] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.491115] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.507681] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.507761] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.524490] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.524594] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.540898] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.540973] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.557558] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.557621] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.574282] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.574342] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.590830] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.590876] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.607492] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.607536] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.624230] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.624278] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.640962] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.641035] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.657554] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.657609] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.674302] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.674359] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.690849] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.690903] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.707677] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.707756] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.724296] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.724349] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.740857] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.740901] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.757536] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.757600] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.774251] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.774293] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.790864] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.790916] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.807527] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.807567] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.824255] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.824303] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.840860] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.840899] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.857507] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.857545] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.874202] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.874245] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.890846] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.890884] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.907532] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.907586] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.924188] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.924228] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.940870] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.940933] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [458.957540] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [458.957582] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [458.974324] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [458.974413] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [458.990884] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [458.990926] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.007586] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.007654] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.024225] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.024287] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.040877] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.040920] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.057670] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.057729] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.074409] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.074489] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.091001] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.091057] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.107619] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.107671] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.124289] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.124340] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.140947] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.140989] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.157708] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.157788] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.174283] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.174335] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.190954] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.190997] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.207614] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.207660] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.224272] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.224310] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.240959] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.241010] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:293] from all active usage due to RMFB ioctl
<7> [459.257619] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.257657] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<7> [459.274413] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:287]
<7> [459.274495] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:289] from all active usage due to RMFB ioctl
<7> [459.291735] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:289]
<7> [459.313451] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [459.327080] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:287] from all active usage due to RMFB ioctl
<6> [462.390267] [IGT] kms_flip: finished subtest A-DP2, FAIL
Created at 2025-02-27 10:38:55