Results for igt@kms_flip@wf_vblank-ts-check@a-edp1

Machine description: shard-mtlp-8

Result: Fail

git-log-oneline i915_display_info18 igt_runner18 runtimes18 results18.json results18-i915-load.json i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 11.99 seconds
Hostname 
shard-mtlp-8
Igt-Version 
IGT-Version: 1.30-gcdae18aa2 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16246-gf811577f4244+ x86_64)
Out 
Using IGT_SRANDOM=1741394221 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-eDP1
  2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa 
Expected frametime: 11111us; measured 11063.1us +- 2.462us accuracy 0.07%
vblank interval differs from modeline! expected 11111.1us, measured 11063us +- 2.462us, difference 48.1us (19.5 sigma)
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 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-eDP1: FAIL (11.993s)
Err 
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-eDP1
(kms_flip:2233) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2233) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:2233) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2233) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=11520)
(kms_flip:2233) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2233) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2233) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=11520)
(kms_flip:2233) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2233) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2233) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2233) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2233) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2233) igt_kms-INFO:   2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa 
(kms_flip:2233) DEBUG: No stale events found
(kms_flip:2233) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2233) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2233) INFO: Expected frametime: 11111us; measured 11063.1us +- 2.462us accuracy 0.07%
(kms_flip:2233) INFO: vblank interval differs from modeline! expected 11111.1us, measured 11063us +- 2.462us, difference 48.1us (19.5 sigma)
(kms_flip:2233) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 66.024002
current_received_ts = 66.024239
current_seq = 1306
count = 0
seq_step = 10
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.024002
last_received_ts = 66.024239
last_seq = 1306
current_ts = 66.134659
current_received_ts = 66.134888
current_seq = 1316
count = 1
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.024002/1306, current 66.134659/1316: elapsed=110654.0us expected=110630.6us +- 553.2us, error 0.0%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.134659
last_received_ts = 66.134888
last_seq = 1316
current_ts = 66.245346
current_received_ts = 66.245461
current_seq = 1326
count = 2
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.134659/1316, current 66.245346/1326: elapsed=110690.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.245346
last_received_ts = 66.245461
last_seq = 1326
current_ts = 66.356041
current_received_ts = 66.356262
current_seq = 1336
count = 3
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.245346/1326, current 66.356041/1336: elapsed=110691.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.356041
last_received_ts = 66.356262
last_seq = 1336
current_ts = 66.466728
current_received_ts = 66.466934
current_seq = 1346
count = 4
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.356041/1336, current 66.466728/1346: elapsed=110688.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.466728
last_received_ts = 66.466934
last_seq = 1346
current_ts = 66.577415
current_received_ts = 66.577682
current_seq = 1356
count = 5
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.466728/1346, current 66.577415/1356: elapsed=110688.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.577415
last_received_ts = 66.577682
last_seq = 1356
current_ts = 66.688103
current_received_ts = 66.688339
current_seq = 1366
count = 6
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.577415/1356, current 66.688103/1366: elapsed=110687.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.688103
last_received_ts = 66.688339
last_seq = 1366
current_ts = 66.798790
current_received_ts = 66.798889
current_seq = 1376
count = 7
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.688103/1366, current 66.798790/1376: elapsed=110690.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.798790
last_received_ts = 66.798889
last_seq = 1376
current_ts = 66.909485
current_received_ts = 66.909653
current_seq = 1386
count = 8
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.798790/1376, current 66.909485/1386: elapsed=110693.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 66.909485
last_received_ts = 66.909653
last_seq = 1386
current_ts = 67.020172
current_received_ts = 67.020195
current_seq = 1396
count = 9
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 66.909485/1386, current 67.020172/1396: elapsed=110687.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.020172
last_received_ts = 67.020195
last_seq = 1396
current_ts = 67.130882
current_received_ts = 67.131203
current_seq = 1406
count = 10
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.020172/1396, current 67.130882/1406: elapsed=110708.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.130882
last_received_ts = 67.131203
last_seq = 1406
current_ts = 67.241623
current_received_ts = 67.241783
current_seq = 1416
count = 11
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.130882/1406, current 67.241623/1416: elapsed=110743.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.241623
last_received_ts = 67.241783
last_seq = 1416
current_ts = 67.352364
current_received_ts = 67.352585
current_seq = 1426
count = 12
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.241623/1416, current 67.352364/1426: elapsed=110739.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.352364
last_received_ts = 67.352585
last_seq = 1426
current_ts = 67.463104
current_received_ts = 67.463341
current_seq = 1436
count = 13
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.352364/1426, current 67.463104/1436: elapsed=110743.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.463104
last_received_ts = 67.463341
last_seq = 1436
current_ts = 67.573845
current_received_ts = 67.574066
current_seq = 1446
count = 14
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.463104/1436, current 67.573845/1446: elapsed=110743.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.573845
last_received_ts = 67.574066
last_seq = 1446
current_ts = 67.684586
current_received_ts = 67.684677
current_seq = 1456
count = 15
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.573845/1446, current 67.684586/1456: elapsed=110741.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.684586
last_received_ts = 67.684677
last_seq = 1456
current_ts = 67.795334
current_received_ts = 67.795563
current_seq = 1466
count = 16
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.684586/1456, current 67.795334/1466: elapsed=110742.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.795334
last_received_ts = 67.795563
last_seq = 1466
current_ts = 67.906075
current_received_ts = 67.905815
current_seq = 1476
count = 17
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.795334/1466, current 67.906075/1476: elapsed=110746.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 67.906075
last_received_ts = 67.905815
last_seq = 1476
current_ts = 68.016815
current_received_ts = 68.016533
current_seq = 1486
count = 18
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 67.906075/1476, current 68.016815/1486: elapsed=110739.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.016815
last_received_ts = 68.016533
last_seq = 1486
current_ts = 68.127571
current_received_ts = 68.127731
current_seq = 1496
count = 19
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.016815/1486, current 68.127571/1496: elapsed=110756.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.127571
last_received_ts = 68.127731
last_seq = 1496
current_ts = 68.238365
current_received_ts = 68.238083
current_seq = 1506
count = 20
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.127571/1496, current 68.238365/1506: elapsed=110790.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.238365
last_received_ts = 68.238083
last_seq = 1506
current_ts = 68.349152
current_received_ts = 68.349289
current_seq = 1516
count = 21
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.238365/1506, current 68.349152/1516: elapsed=110791.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.349152
last_received_ts = 68.349289
last_seq = 1516
current_ts = 68.459938
current_received_ts = 68.460106
current_seq = 1526
count = 22
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.349152/1516, current 68.459938/1526: elapsed=110787.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.459938
last_received_ts = 68.460106
last_seq = 1526
current_ts = 68.570724
current_received_ts = 68.570984
current_seq = 1536
count = 23
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.459938/1526, current 68.570724/1536: elapsed=110784.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.570724
last_received_ts = 68.570984
last_seq = 1536
current_ts = 68.681519
current_received_ts = 68.681770
current_seq = 1546
count = 24
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.570724/1536, current 68.681519/1546: elapsed=110791.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.681519
last_received_ts = 68.681770
last_seq = 1546
current_ts = 68.792305
current_received_ts = 68.792465
current_seq = 1556
count = 25
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.681519/1546, current 68.792305/1556: elapsed=110791.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.792305
last_received_ts = 68.792465
last_seq = 1556
current_ts = 68.903091
current_received_ts = 68.903358
current_seq = 1566
count = 26
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.792305/1556, current 68.903091/1566: elapsed=110788.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 68.903091
last_received_ts = 68.903358
last_seq = 1566
current_ts = 69.013885
current_received_ts = 69.013824
current_seq = 1576
count = 27
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 68.903091/1566, current 69.013885/1576: elapsed=110788.0us expected=110630.6us +- 553.2us, error 0.1%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.013885
last_received_ts = 69.013824
last_seq = 1576
current_ts = 69.124687
current_received_ts = 69.124641
current_seq = 1586
count = 28
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.013885/1576, current 69.124687/1586: elapsed=110802.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.124687
last_received_ts = 69.124641
last_seq = 1586
current_ts = 69.235512
current_received_ts = 69.235748
current_seq = 1596
count = 29
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.124687/1586, current 69.235512/1596: elapsed=110827.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.235512
last_received_ts = 69.235748
last_seq = 1596
current_ts = 69.346336
current_received_ts = 69.346581
current_seq = 1606
count = 30
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.235512/1596, current 69.346336/1606: elapsed=110828.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.346336
last_received_ts = 69.346581
last_seq = 1606
current_ts = 69.457169
current_received_ts = 69.457413
current_seq = 1616
count = 31
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.346336/1606, current 69.457169/1616: elapsed=110831.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.457169
last_received_ts = 69.457413
last_seq = 1616
current_ts = 69.568001
current_received_ts = 69.567825
current_seq = 1626
count = 32
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.457169/1616, current 69.568001/1626: elapsed=110829.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.568001
last_received_ts = 69.567825
last_seq = 1626
current_ts = 69.678825
current_received_ts = 69.679138
current_seq = 1636
count = 33
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.568001/1626, current 69.678825/1636: elapsed=110828.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.678825
last_received_ts = 69.679138
last_seq = 1636
current_ts = 69.789658
current_received_ts = 69.789597
current_seq = 1646
count = 34
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.678825/1636, current 69.789658/1646: elapsed=110830.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.789658
last_received_ts = 69.789597
last_seq = 1646
current_ts = 69.900482
current_received_ts = 69.900635
current_seq = 1656
count = 35
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.789658/1646, current 69.900482/1656: elapsed=110823.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 69.900482
last_received_ts = 69.900635
last_seq = 1656
current_ts = 70.011314
current_received_ts = 70.011421
current_seq = 1666
count = 36
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 69.900482/1656, current 70.011314/1666: elapsed=110832.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.011314
last_received_ts = 70.011421
last_seq = 1666
current_ts = 70.122147
current_received_ts = 70.122383
current_seq = 1676
count = 37
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.011314/1666, current 70.122147/1676: elapsed=110838.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.122147
last_received_ts = 70.122383
last_seq = 1676
current_ts = 70.233017
current_received_ts = 70.232971
current_seq = 1686
count = 38
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.122147/1676, current 70.233017/1686: elapsed=110865.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.233017
last_received_ts = 70.232971
last_seq = 1686
current_ts = 70.343880
current_received_ts = 70.344116
current_seq = 1696
count = 39
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.233017/1686, current 70.343880/1696: elapsed=110864.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.343880
last_received_ts = 70.344116
last_seq = 1696
current_ts = 70.454742
current_received_ts = 70.454636
current_seq = 1706
count = 40
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.343880/1696, current 70.454742/1706: elapsed=110860.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.454742
last_received_ts = 70.454636
last_seq = 1706
current_ts = 70.565605
current_received_ts = 70.565453
current_seq = 1716
count = 41
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.454742/1706, current 70.565605/1716: elapsed=110868.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.565605
last_received_ts = 70.565453
last_seq = 1716
current_ts = 70.676468
current_received_ts = 70.676659
current_seq = 1726
count = 42
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.565605/1716, current 70.676468/1726: elapsed=110861.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.676468
last_received_ts = 70.676659
last_seq = 1726
current_ts = 70.787338
current_received_ts = 70.787239
current_seq = 1736
count = 43
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.676468/1726, current 70.787338/1736: elapsed=110868.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.787338
last_received_ts = 70.787239
last_seq = 1736
current_ts = 70.898201
current_received_ts = 70.897972
current_seq = 1746
count = 44
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.787338/1736, current 70.898201/1746: elapsed=110863.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 70.898201
last_received_ts = 70.897972
last_seq = 1746
current_ts = 71.009064
current_received_ts = 71.008804
current_seq = 1756
count = 45
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 70.898201/1746, current 71.009064/1756: elapsed=110866.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.009064
last_received_ts = 71.008804
last_seq = 1756
current_ts = 71.119934
current_received_ts = 71.119629
current_seq = 1766
count = 46
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.009064/1756, current 71.119934/1766: elapsed=110868.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.119934
last_received_ts = 71.119629
last_seq = 1766
current_ts = 71.230827
current_received_ts = 71.230652
current_seq = 1776
count = 47
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.119934/1766, current 71.230827/1776: elapsed=110895.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.230827
last_received_ts = 71.230652
last_seq = 1776
current_ts = 71.341721
current_received_ts = 71.341446
current_seq = 1786
count = 48
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.230827/1776, current 71.341721/1786: elapsed=110895.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.341721
last_received_ts = 71.341446
last_seq = 1786
current_ts = 71.452621
current_received_ts = 71.452339
current_seq = 1796
count = 49
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.341721/1786, current 71.452621/1796: elapsed=110895.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.452621
last_received_ts = 71.452339
last_seq = 1796
current_ts = 71.563515
current_received_ts = 71.563255
current_seq = 1806
count = 50
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.452621/1796, current 71.563515/1806: elapsed=110898.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.563515
last_received_ts = 71.563255
last_seq = 1806
current_ts = 71.674408
current_received_ts = 71.674202
current_seq = 1816
count = 51
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.563515/1806, current 71.674408/1816: elapsed=110895.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.674408
last_received_ts = 71.674202
last_seq = 1816
current_ts = 71.785301
current_received_ts = 71.785103
current_seq = 1826
count = 52
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.674408/1816, current 71.785301/1826: elapsed=110892.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.785301
last_received_ts = 71.785103
last_seq = 1826
current_ts = 71.896202
current_received_ts = 71.896080
current_seq = 1836
count = 53
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.785301/1826, current 71.896202/1836: elapsed=110899.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 71.896202
last_received_ts = 71.896080
last_seq = 1836
current_ts = 72.007095
current_received_ts = 72.007263
current_seq = 1846
count = 54
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 71.896202/1836, current 72.007095/1846: elapsed=110893.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.007095
last_received_ts = 72.007263
last_seq = 1846
current_ts = 72.117996
current_received_ts = 72.118240
current_seq = 1856
count = 55
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.007095/1846, current 72.117996/1856: elapsed=110899.0us expected=110630.6us +- 553.2us, error 0.2%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.117996
last_received_ts = 72.118240
last_seq = 1856
current_ts = 72.228920
current_received_ts = 72.228722
current_seq = 1866
count = 56
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.117996/1856, current 72.228920/1866: elapsed=110925.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.228920
last_received_ts = 72.228722
last_seq = 1866
current_ts = 72.339844
current_received_ts = 72.339806
current_seq = 1876
count = 57
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.228920/1866, current 72.339844/1876: elapsed=110921.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.339844
last_received_ts = 72.339806
last_seq = 1876
current_ts = 72.450760
current_received_ts = 72.450615
current_seq = 1886
count = 58
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.339844/1876, current 72.450760/1886: elapsed=110922.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.450760
last_received_ts = 72.450615
last_seq = 1886
current_ts = 72.561684
current_received_ts = 72.561852
current_seq = 1896
count = 59
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.450760/1886, current 72.561684/1896: elapsed=110921.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.561684
last_received_ts = 72.561852
last_seq = 1896
current_ts = 72.672607
current_received_ts = 72.672791
current_seq = 1906
count = 60
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.561684/1896, current 72.672607/1906: elapsed=110922.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.672607
last_received_ts = 72.672791
last_seq = 1906
current_ts = 72.783531
current_received_ts = 72.783707
current_seq = 1916
count = 61
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.672607/1906, current 72.783531/1916: elapsed=110926.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.783531
last_received_ts = 72.783707
last_seq = 1916
current_ts = 72.894447
current_received_ts = 72.894646
current_seq = 1926
count = 62
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.783531/1916, current 72.894447/1926: elapsed=110918.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 72.894447
last_received_ts = 72.894646
last_seq = 1926
current_ts = 73.005379
current_received_ts = 73.005280
current_seq = 1936
count = 63
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 72.894447/1926, current 73.005379/1936: elapsed=110926.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 73.005379
last_received_ts = 73.005280
last_seq = 1936
current_ts = 73.116295
current_received_ts = 73.116455
current_seq = 1946
count = 64
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 73.005379/1936, current 73.116295/1946: elapsed=110922.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 73.116295
last_received_ts = 73.116455
last_seq = 1946
current_ts = 73.227249
current_received_ts = 73.227180
current_seq = 1956
count = 65
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 73.116295/1946, current 73.227249/1956: elapsed=110951.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 73.227249
last_received_ts = 73.227180
last_seq = 1956
current_ts = 73.338188
current_received_ts = 73.337959
current_seq = 1966
count = 66
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 73.227249/1956, current 73.338188/1966: elapsed=110941.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: name = vblank
last_ts = 73.338188
last_received_ts = 73.337959
last_seq = 1966
current_ts = 73.449135
current_received_ts = 73.449326
current_seq = 1976
count = 67
seq_step = 10
(kms_flip:2233) DEBUG: vblank ts/seq: last 73.338188/1966, current 73.449135/1976: elapsed=110946.0us expected=110630.6us +- 553.2us, error 0.3%
(kms_flip:2233) DEBUG: expected 681, counted 680, encoder type 2
(kms_flip:2233) DEBUG: dropped frames, expected 681, counted 680, encoder type 2
(kms_flip:2233) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2233) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2233) igt_core-INFO: Stack trace:
(kms_flip:2233) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2233) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2233) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2233) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2233) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2233) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2233) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2233) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (11.993s)
Dmesg
<6> [64.633909] Console: switching to colour dummy device 80x25
<6> [64.633949] [IGT] kms_flip: executing
<7> [64.642246] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [64.643592] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [64.662615] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [64.667769] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1]
<7> [64.667785] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:250:DP-1]
<7> [64.672594] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1] disconnected
<7> [64.672858] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1]
<7> [64.672867] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:262:HDMI-A-1]
<7> [64.677608] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1] disconnected
<7> [64.677866] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2]
<7> [64.677873] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:268:DP-2]
<7> [64.678107] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt)
<7> [64.678291] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2] disconnected
<7> [64.678539] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [64.678558] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [64.678812] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> legacy)
<7> [64.683585] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [64.683849] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3]
<7> [64.683856] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-3]
<7> [64.684094] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt)
<7> [64.684281] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3] disconnected
<7> [64.684531] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4]
<7> [64.684539] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:290:DP-4]
<7> [64.684774] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt)
<7> [64.684961] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4] disconnected
<7> [64.685307] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:eDP-1]
<7> [64.685316] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:241:eDP-1]
<7> [64.686232] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [64.687056] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [64.687171] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000
<7> [64.687286] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000
<7> [64.687429] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [64.687437] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] ELD monitor
<7> [64.687439] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:241:eDP-1] ELD size 20, SAD count 0
<7> [64.687451] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] VRR capable: no
<7> [64.687565] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [64.688504] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [64.688685] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:241:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [64.690147] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:eDP-1] probed modes:
<7> [64.690157] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [64.690174] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1]
<7> [64.690181] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:250:DP-1]
<7> [64.694639] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:250:DP-1] disconnected
<7> [64.694657] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1]
<7> [64.694664] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:262:HDMI-A-1]
<7> [64.699664] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:262:HDMI-A-1] disconnected
<7> [64.699681] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2]
<7> [64.699689] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:268:DP-2]
<7> [64.699904] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:268:DP-2] disconnected
<7> [64.699917] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [64.699925] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [64.704722] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [64.704764] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3]
<7> [64.704780] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-3]
<7> [64.705148] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-3] disconnected
<7> [64.705174] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4]
<7> [64.705188] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:290:DP-4]
<7> [64.705474] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:290:DP-4] disconnected
<6> [64.705858] [IGT] kms_flip: starting subtest wf_vblank-ts-check
<6> [64.708041] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [64.708306] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:298]
<7> [64.708348] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:301]
<7> [64.717655] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.718119] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.776631] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.776846] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.796258] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.796462] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.820618] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.820849] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.846490] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [64.846572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [64.846707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [64.846832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [64.846953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [64.847074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0)
<7> [64.847195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [64.847318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [64.847458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [64.847582] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [64.847702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [64.847821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [64.847945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [64.848065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [64.848184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [64.848302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [64.848432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [64.848554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [64.848674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [64.848794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [64.848913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [64.849032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [64.849150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [64.849269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [64.849396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [64.849517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [64.849636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [64.849756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [64.849875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [64.849994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [64.850113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [64.850231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [64.850349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [64.850493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [64.850622] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [64.850749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [64.850871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [64.850993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [64.851113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [64.851233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 540000, found 0)
<7> [64.851353] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [64.851536] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [64.851661] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [64.851807] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0
<7> [64.851927] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0
<7> [64.852044] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [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> [64.852160] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.852279] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.852418] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.852580] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [64.852705] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200
<7> [64.852827] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [64.852947] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [64.853067] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [64.853187] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0
<7> [64.853308] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [64.853444] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [64.853568] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [64.853708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [64.853834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [64.853955] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [64.854075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [64.854196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [64.854314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [64.854447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [64.854612] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2
<7> [64.856039] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [65.059783] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:241:eDP-1] set backlight PWM = 0
<7> [65.061050] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [65.067031] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turn panel power off
<7> [65.067429] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000
<7> [65.067502] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [65.067975] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [65.067906] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - short
<7> [65.119666] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [65.119973] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [65.120184] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [65.120271] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [65.120435] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - long
<7> [65.120865] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [65.120959] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [65.121291] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [65.121419] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:249:DDI B/PHY B]
<7> [65.122013] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:251:DP-MST A]
<7> [65.122430] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:252:DP-MST B]
<7> [65.122703] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DP-MST C]
<7> [65.123022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:254:DP-MST D]
<7> [65.123274] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:267:DDI TC1/PHY TC1]
<7> [65.123525] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:269:DP-MST A]
<7> [65.123784] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST B]
<7> [65.124032] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST C]
<7> [65.124273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST D]
<7> [65.124545] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:276:DDI TC2/PHY TC2]
<7> [65.124867] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI TC3/PHY TC3]
<7> [65.125260] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [65.125502] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [65.125688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [65.125890] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [65.126098] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:289:DDI TC4/PHY TC4]
<7> [65.126253] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:291:DP-MST A]
<7> [65.126382] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:292:DP-MST B]
<7> [65.126525] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:293:DP-MST C]
<7> [65.126649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:294:DP-MST D]
<7> [65.126761] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:241:eDP-1]
<7> [65.126926] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [65.127067] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: *)
<7> [65.127184] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [65.127397] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [65.127606] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [65.127775] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [65.127936] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432
<7> [65.128105] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [65.128244] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [65.128329] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [65.128415] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [65.128441] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:241:eDP-1]
<7> [65.128501] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:241:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [65.128633] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:240:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 max link_bpp 30.0000
<7> [65.128748] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 link rate required 1926825 available 2160000
<7> [65.128881] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [65.129005] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:240:DDI A/PHY A] [CRTC:82:pipe A]
<7> [65.129119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [65.129241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [65.129361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [65.129481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [65.129601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [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 7483046/8388608 link 498869/524288)
<7> [65.129723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [65.129857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [65.129980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [65.130100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [65.130220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [65.130339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [65.130459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [65.130578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [65.130696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [65.130837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [65.130959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [65.131076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [65.131192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [65.131308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [65.131424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [65.131541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [65.131660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [65.131779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [65.131918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [65.132039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [65.132157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [65.132273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [65.132389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [65.132508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [65.132627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [65.132747] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [65.132874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [65.132995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [65.133114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [65.133233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [65.133351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [65.133467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [65.133585] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [65.133702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 540000)
<7> [65.133825] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [65.134168] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [65.134285] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [65.134431] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001
<7> [65.134546] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95
<7> [65.134660] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [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> [65.134773] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0
<7> [65.134903] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87
<7> [65.135021] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88
<7> [65.135139] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 2055280 num active planes 1
<7> [65.135265] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [65.135388] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [65.135510] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [65.135631] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [65.135751] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [65.135880] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (256910 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [65.136004] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (40143 kHz) > old min cdclk (0 kHz)
<7> [65.136129] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [65.136249] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz
<7> [65.136369] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [65.136502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [65.136625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [65.136746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [65.136876] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [65.136996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [65.137114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [65.137233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [65.137352] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64
<7> [65.137470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [65.137589] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [65.137709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [65.137832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [65.137951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0
<7> [65.138070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [65.138189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14
<7> [65.138308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [65.138426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [65.138543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [65.138659] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [65.138776] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [65.138914] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [65.139037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [65.139155] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [65.139274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [65.139393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [65.139512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [65.139630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [65.139749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa
<7> [65.139887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [65.140008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820
<7> [65.140126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [65.140243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [65.140360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [65.140478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [65.140596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x83d60, dpll_md: 0xf42110, fp0: 0xf8, fp1: 0x1000000
<7> [65.140714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [65.140834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [65.140990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [65.141112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.141231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.141349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.141468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [65.141586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [65.141703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.141823] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.141974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [65.142094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [65.142212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:298] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [65.142333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [65.142451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [65.142570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [65.142688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [65.142804] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [65.142945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [65.143066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [65.145930] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [65.146378] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [65.146774] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [65.147180] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [65.147316] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [65.147743] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [65.147960] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [65.148121] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768
<7> [65.148243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [65.148360] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:249:DDI B/PHY B]
<7> [65.148477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:251:DP-MST A]
<7> [65.148591] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:252:DP-MST B]
<7> [65.148704] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DP-MST C]
<7> [65.148817] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:254:DP-MST D]
<7> [65.148970] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:267:DDI TC1/PHY TC1]
<7> [65.149089] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:269:DP-MST A]
<7> [65.149203] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST B]
<7> [65.149315] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST C]
<7> [65.149426] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST D]
<7> [65.149536] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:276:DDI TC2/PHY TC2]
<7> [65.149646] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI TC3/PHY TC3]
<7> [65.149758] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [65.149867] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [65.149992] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [65.150105] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [65.150216] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:289:DDI TC4/PHY TC4]
<7> [65.150326] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:291:DP-MST A]
<7> [65.150437] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:292:DP-MST B]
<7> [65.150546] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:293:DP-MST C]
<7> [65.150656] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:294:DP-MST D]
<7> [65.150768] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [65.150993] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)
<7> [65.151119] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [65.151276] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [65.152681] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turn panel power on
<7> [65.152831] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power cycle (468 ms remaining)
<7> [65.630341] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [65.692174] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [65.692801] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 wait for panel power on
<7> [65.693379] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [65.724759] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [65.725228] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - long
<7> [65.725659] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [65.726191] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:240:DDI A/PHY A]
<7> [65.742737] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected)
<7> [65.742841] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected)
<7> [65.742897] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected)
<7> [65.743016] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected)
<7> [65.894002] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [65.896562] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD on
<7> [65.897002] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [65.900872] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80
<7> [65.901727] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [65.902690] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07
<7> [65.903673] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [65.904357] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [65.905461] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Clock recovery OK
<7> [65.905574] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [65.907056] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [65.907173] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:241:eDP-1][ENCODER:240:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4
<7> [65.907767] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [65.907985] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [65.908099] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A
<7> [65.908281] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:241:eDP-1] set backlight PWM = 13892
<7> [65.919173] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2
<7> [65.920655] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:241:eDP-1]
<7> [65.920832] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [68.441406] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD off
<7> [68.442002] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [68.442530] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [68.443083] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [68.443574] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [76.700685] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2025-03-10 16:11:49