Results for igt@kms_flip@blocking-wf_vblank

Machine description: shard-lnl-4

Result: Fail

i915_display_info7 igt_runner7 results7.json results7-xe-load.json i915_display_info_post_exec7 boot7 dmesg7

DetailValue
Duration 28.13 seconds
Hostname 
shard-lnl-4
Igt-Version 
IGT-Version: 1.30-gaa097b226 (x86_64) (Linux: 6.14.0-rc3-xe+ x86_64)
Out 
Using IGT_SRANDOM=1740091994 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Expected frametime: 16664us; measured 17198.8us +- 3.371us accuracy 0.06%
vblank interval differs from modeline! expected 16664.0us, measured 17199us +- 3.371us, difference 534.8us (158.7 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __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 (5.657s)
Starting dynamic subtest: B-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Expected frametime: 16664us; measured 16908.6us +- 3.949us accuracy 0.07%
vblank interval differs from modeline! expected 16664.0us, measured 16909us +- 3.949us, difference 244.6us (61.9 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __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 B-eDP1: FAIL (7.899s)
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Expected frametime: 16664us; measured 16753.6us +- 4.472us accuracy 0.08%
vblank interval differs from modeline! expected 16664.0us, measured 16754us +- 4.472us, difference 89.6us (20.0 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __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 C-eDP1: FAIL (14.570s)
Subtest blocking-wf_vblank: FAIL (28.132s)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2246 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2930 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2386 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-eDP1
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9540c50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9540c50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9528cf0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9528cf0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9528cf0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9528cf0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9584e50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f9584e50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) igt_kms-INFO:   2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
(kms_flip:4043) DEBUG: No stale events found
(kms_flip:4043) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4043) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4043) INFO: Expected frametime: 16664us; measured 17198.8us +- 3.371us accuracy 0.06%
(kms_flip:4043) INFO: vblank interval differs from modeline! expected 16664.0us, measured 17199us +- 3.371us, difference 534.8us (158.7 sigma)
(kms_flip:4043) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 313.655029
current_received_ts = 313.654602
current_seq = 10699
count = 0
seq_step = 10
(kms_flip:4043) DEBUG: name = vblank
last_ts = 313.655029
last_received_ts = 313.654602
last_seq = 10699
current_ts = 313.826355
current_received_ts = 313.825897
current_seq = 10709
count = 1
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 313.655029/10699, current 313.826355/10709: elapsed=171319.0us expected=171988.1us +- 859.9us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 313.826355
last_received_ts = 313.825897
last_seq = 10709
current_ts = 313.997681
current_received_ts = 313.997223
current_seq = 10719
count = 2
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 313.826355/10709, current 313.997681/10719: elapsed=171328.0us expected=171988.1us +- 859.9us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 313.997681
last_received_ts = 313.997223
last_seq = 10719
current_ts = 314.169006
current_received_ts = 314.168549
current_seq = 10729
count = 3
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 313.997681/10719, current 314.169006/10729: elapsed=171329.0us expected=171988.1us +- 859.9us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 314.169006
last_received_ts = 314.168549
last_seq = 10729
current_ts = 314.340332
current_received_ts = 314.339874
current_seq = 10739
count = 4
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 314.169006/10729, current 314.340332/10739: elapsed=171328.0us expected=171988.1us +- 859.9us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 314.340332
last_received_ts = 314.339874
last_seq = 10739
current_ts = 314.511597
current_received_ts = 314.511169
current_seq = 10749
count = 5
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 314.340332/10739, current 314.511597/10749: elapsed=171265.0us expected=171988.1us +- 859.9us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 314.511597
last_received_ts = 314.511169
last_seq = 10749
current_ts = 314.682343
current_received_ts = 314.681732
current_seq = 10759
count = 6
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 314.511597/10749, current 314.682343/10759: elapsed=170751.0us expected=171988.1us +- 859.9us, error 0.7%
(kms_flip:4043) DEBUG: inconsistent vblank ts/seq: last 314.511597/10749, current 314.682343/10759: elapsed=170751.0us expected=171988.1us
(kms_flip:4043) DEBUG: expected 0, counted 60, encoder type 2
(kms_flip:4043) DEBUG: dropped frames, expected 0, counted 60, encoder type 2
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:4043) igt_core-INFO: Stack trace:
(kms_flip:4043) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:4043) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:4043) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:4043) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:4043) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:4043) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4043) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4043) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (5.657s)
Starting dynamic subtest: B-eDP1
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6320, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6320, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6420, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6420, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6320, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6320, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6420, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c6420, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) igt_kms-INFO:   2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
(kms_flip:4043) DEBUG: No stale events found
(kms_flip:4043) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4043) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4043) INFO: Expected frametime: 16664us; measured 16908.6us +- 3.949us accuracy 0.07%
(kms_flip:4043) INFO: vblank interval differs from modeline! expected 16664.0us, measured 16909us +- 3.949us, difference 244.6us (61.9 sigma)
(kms_flip:4043) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 319.467590
current_received_ts = 319.467102
current_seq = 323
count = 0
seq_step = 10
(kms_flip:4043) DEBUG: name = vblank
last_ts = 319.467590
last_received_ts = 319.467102
last_seq = 323
current_ts = 319.636414
current_received_ts = 319.635956
current_seq = 333
count = 1
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 319.467590/323, current 319.636414/333: elapsed=168819.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 319.636414
last_received_ts = 319.635956
last_seq = 333
current_ts = 319.805176
current_received_ts = 319.804749
current_seq = 343
count = 2
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 319.636414/333, current 319.805176/343: elapsed=168787.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 319.805176
last_received_ts = 319.804749
last_seq = 343
current_ts = 319.973969
current_received_ts = 319.973511
current_seq = 353
count = 3
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 319.805176/343, current 319.973969/353: elapsed=168790.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 319.973969
last_received_ts = 319.973511
last_seq = 353
current_ts = 320.142761
current_received_ts = 320.141876
current_seq = 363
count = 4
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 319.973969/353, current 320.142761/363: elapsed=168785.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.142761
last_received_ts = 320.141876
last_seq = 363
current_ts = 320.311554
current_received_ts = 320.311127
current_seq = 373
count = 5
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.142761/363, current 320.311554/373: elapsed=168792.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.311554
last_received_ts = 320.311127
last_seq = 373
current_ts = 320.480347
current_received_ts = 320.479889
current_seq = 383
count = 6
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.311554/373, current 320.480347/383: elapsed=168791.0us expected=169085.6us +- 845.4us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.480347
last_received_ts = 320.479889
last_seq = 383
current_ts = 320.648895
current_received_ts = 320.648407
current_seq = 393
count = 7
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.480347/383, current 320.648895/393: elapsed=168533.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.648895
last_received_ts = 320.648407
last_seq = 393
current_ts = 320.817413
current_received_ts = 320.816833
current_seq = 403
count = 8
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.648895/393, current 320.817413/403: elapsed=168526.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.817413
last_received_ts = 320.816833
last_seq = 403
current_ts = 320.985931
current_received_ts = 320.985474
current_seq = 413
count = 9
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.817413/403, current 320.985931/413: elapsed=168538.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 320.985931
last_received_ts = 320.985474
last_seq = 413
current_ts = 321.154480
current_received_ts = 321.153992
current_seq = 423
count = 10
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 320.985931/413, current 321.154480/423: elapsed=168533.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.154480
last_received_ts = 321.153992
last_seq = 423
current_ts = 321.322998
current_received_ts = 321.322571
current_seq = 433
count = 11
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.154480/423, current 321.322998/433: elapsed=168527.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.322998
last_received_ts = 321.322571
last_seq = 433
current_ts = 321.491547
current_received_ts = 321.491089
current_seq = 443
count = 12
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.322998/433, current 321.491547/443: elapsed=168535.0us expected=169085.6us +- 845.4us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.491547
last_received_ts = 321.491089
last_seq = 443
current_ts = 321.659851
current_received_ts = 321.659363
current_seq = 453
count = 13
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.491547/443, current 321.659851/453: elapsed=168305.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.659851
last_received_ts = 321.659363
last_seq = 453
current_ts = 321.828156
current_received_ts = 321.827728
current_seq = 463
count = 14
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.659851/453, current 321.828156/463: elapsed=168304.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.828156
last_received_ts = 321.827728
last_seq = 463
current_ts = 321.996460
current_received_ts = 321.995972
current_seq = 473
count = 15
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.828156/463, current 321.996460/473: elapsed=168304.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 321.996460
last_received_ts = 321.995972
last_seq = 473
current_ts = 322.164764
current_received_ts = 322.164276
current_seq = 483
count = 16
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 321.996460/473, current 322.164764/483: elapsed=168305.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 322.164764
last_received_ts = 322.164276
last_seq = 483
current_ts = 322.333069
current_received_ts = 322.332611
current_seq = 493
count = 17
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 322.164764/483, current 322.333069/493: elapsed=168302.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 322.333069
last_received_ts = 322.332611
last_seq = 493
current_ts = 322.501343
current_received_ts = 322.500885
current_seq = 503
count = 18
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 322.333069/493, current 322.501343/503: elapsed=168291.0us expected=169085.6us +- 845.4us, error 0.5%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 322.501343
last_received_ts = 322.500885
last_seq = 503
current_ts = 322.669464
current_received_ts = 322.668976
current_seq = 513
count = 19
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 322.501343/503, current 322.669464/513: elapsed=168102.0us expected=169085.6us +- 845.4us, error 0.6%
(kms_flip:4043) DEBUG: inconsistent vblank ts/seq: last 322.501343/503, current 322.669464/513: elapsed=168102.0us expected=169085.6us
(kms_flip:4043) DEBUG: expected 0, counted 190, encoder type 2
(kms_flip:4043) DEBUG: dropped frames, expected 0, counted 190, encoder type 2
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:4043) igt_core-INFO: Stack trace:
(kms_flip:4043) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:4043) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:4043) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:4043) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:4043) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:4043) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4043) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4043) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-eDP1: FAIL (7.899s)
Starting dynamic subtest: C-eDP1
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:4043) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=11520)
(kms_flip:4043) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aed30, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aed30, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aee70, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aee70, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aee70, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95aee70, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c9b50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) intel_allocator_simple-DEBUG: <ial: 0x63d7f95c9b50, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4043) igt_kms-INFO:   2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
(kms_flip:4043) DEBUG: No stale events found
(kms_flip:4043) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4043) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4043) INFO: Expected frametime: 16664us; measured 16753.6us +- 4.472us accuracy 0.08%
(kms_flip:4043) INFO: vblank interval differs from modeline! expected 16664.0us, measured 16754us +- 4.472us, difference 89.6us (20.0 sigma)
(kms_flip:4043) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 327.423370
current_received_ts = 327.422821
current_seq = 213
count = 0
seq_step = 10
(kms_flip:4043) DEBUG: name = vblank
last_ts = 327.423370
last_received_ts = 327.422821
last_seq = 213
current_ts = 327.590851
current_received_ts = 327.590271
current_seq = 223
count = 1
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 327.423370/213, current 327.590851/223: elapsed=167473.0us expected=167535.6us +- 837.7us, error 0.0%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 327.590851
last_received_ts = 327.590271
last_seq = 223
current_ts = 327.758270
current_received_ts = 327.757751
current_seq = 233
count = 2
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 327.590851/223, current 327.758270/233: elapsed=167435.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 327.758270
last_received_ts = 327.757751
last_seq = 233
current_ts = 327.925720
current_received_ts = 327.925171
current_seq = 243
count = 3
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 327.758270/233, current 327.925720/243: elapsed=167435.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 327.925720
last_received_ts = 327.925171
last_seq = 243
current_ts = 328.093140
current_received_ts = 328.092621
current_seq = 253
count = 4
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 327.925720/243, current 328.093140/253: elapsed=167434.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.093140
last_received_ts = 328.092621
last_seq = 253
current_ts = 328.260590
current_received_ts = 328.259979
current_seq = 263
count = 5
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.093140/253, current 328.260590/263: elapsed=167434.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.260590
last_received_ts = 328.259979
last_seq = 263
current_ts = 328.428009
current_received_ts = 328.427460
current_seq = 273
count = 6
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.260590/263, current 328.428009/273: elapsed=167434.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.428009
last_received_ts = 328.427460
last_seq = 273
current_ts = 328.595398
current_received_ts = 328.594879
current_seq = 283
count = 7
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.428009/273, current 328.595398/283: elapsed=167379.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.595398
last_received_ts = 328.594879
last_seq = 283
current_ts = 328.762726
current_received_ts = 328.762177
current_seq = 293
count = 8
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.595398/283, current 328.762726/293: elapsed=167345.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.762726
last_received_ts = 328.762177
last_seq = 293
current_ts = 328.930084
current_received_ts = 328.929535
current_seq = 303
count = 9
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.762726/293, current 328.930084/303: elapsed=167344.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 328.930084
last_received_ts = 328.929535
last_seq = 303
current_ts = 329.097412
current_received_ts = 329.096863
current_seq = 313
count = 10
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 328.930084/303, current 329.097412/313: elapsed=167339.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.097412
last_received_ts = 329.096863
last_seq = 313
current_ts = 329.264771
current_received_ts = 329.264191
current_seq = 323
count = 11
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.097412/313, current 329.264771/323: elapsed=167347.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.264771
last_received_ts = 329.264191
last_seq = 323
current_ts = 329.432129
current_received_ts = 329.431580
current_seq = 333
count = 12
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.264771/323, current 329.432129/333: elapsed=167349.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.432129
last_received_ts = 329.431580
last_seq = 333
current_ts = 329.599396
current_received_ts = 329.598846
current_seq = 343
count = 13
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.432129/333, current 329.599396/343: elapsed=167285.0us expected=167535.6us +- 837.7us, error 0.1%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.599396
last_received_ts = 329.598846
last_seq = 343
current_ts = 329.766663
current_received_ts = 329.766113
current_seq = 353
count = 14
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.599396/343, current 329.766663/353: elapsed=167264.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.766663
last_received_ts = 329.766113
last_seq = 353
current_ts = 329.933929
current_received_ts = 329.933380
current_seq = 363
count = 15
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.766663/353, current 329.933929/363: elapsed=167273.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 329.933929
last_received_ts = 329.933380
last_seq = 363
current_ts = 330.101196
current_received_ts = 330.100647
current_seq = 373
count = 16
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 329.933929/363, current 330.101196/373: elapsed=167267.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.101196
last_received_ts = 330.100647
last_seq = 373
current_ts = 330.268463
current_received_ts = 330.267944
current_seq = 383
count = 17
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.101196/373, current 330.268463/383: elapsed=167262.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.268463
last_received_ts = 330.267944
last_seq = 383
current_ts = 330.435730
current_received_ts = 330.435181
current_seq = 393
count = 18
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.268463/383, current 330.435730/393: elapsed=167268.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.435730
last_received_ts = 330.435181
last_seq = 393
current_ts = 330.602966
current_received_ts = 330.602386
current_seq = 403
count = 19
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.435730/393, current 330.602966/403: elapsed=167224.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.602966
last_received_ts = 330.602386
last_seq = 403
current_ts = 330.770172
current_received_ts = 330.769592
current_seq = 413
count = 20
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.602966/403, current 330.770172/413: elapsed=167197.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.770172
last_received_ts = 330.769592
last_seq = 413
current_ts = 330.937347
current_received_ts = 330.936798
current_seq = 423
count = 21
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.770172/413, current 330.937347/423: elapsed=167198.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 330.937347
last_received_ts = 330.936798
last_seq = 423
current_ts = 331.104553
current_received_ts = 331.104004
current_seq = 433
count = 22
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 330.937347/423, current 331.104553/433: elapsed=167195.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.104553
last_received_ts = 331.104004
last_seq = 433
current_ts = 331.271759
current_received_ts = 331.271240
current_seq = 443
count = 23
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.104553/433, current 331.271759/443: elapsed=167206.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.271759
last_received_ts = 331.271240
last_seq = 443
current_ts = 331.438965
current_received_ts = 331.438416
current_seq = 453
count = 24
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.271759/443, current 331.438965/453: elapsed=167194.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.438965
last_received_ts = 331.438416
last_seq = 453
current_ts = 331.606110
current_received_ts = 331.605560
current_seq = 463
count = 25
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.438965/453, current 331.606110/463: elapsed=167158.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.606110
last_received_ts = 331.605560
last_seq = 463
current_ts = 331.773254
current_received_ts = 331.772705
current_seq = 473
count = 26
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.606110/463, current 331.773254/473: elapsed=167138.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.773254
last_received_ts = 331.772705
last_seq = 473
current_ts = 331.940399
current_received_ts = 331.939850
current_seq = 483
count = 27
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.773254/473, current 331.940399/483: elapsed=167141.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 331.940399
last_received_ts = 331.939850
last_seq = 483
current_ts = 332.107544
current_received_ts = 332.107086
current_seq = 493
count = 28
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 331.940399/483, current 332.107544/493: elapsed=167143.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.107544
last_received_ts = 332.107086
last_seq = 493
current_ts = 332.274658
current_received_ts = 332.274200
current_seq = 503
count = 29
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.107544/493, current 332.274658/503: elapsed=167135.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.274658
last_received_ts = 332.274200
last_seq = 503
current_ts = 332.441803
current_received_ts = 332.441376
current_seq = 513
count = 30
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.274658/503, current 332.441803/513: elapsed=167142.0us expected=167535.6us +- 837.7us, error 0.2%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.441803
last_received_ts = 332.441376
last_seq = 513
current_ts = 332.608917
current_received_ts = 332.608429
current_seq = 523
count = 31
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.441803/513, current 332.608917/523: elapsed=167099.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.608917
last_received_ts = 332.608429
last_seq = 523
current_ts = 332.776001
current_received_ts = 332.775543
current_seq = 533
count = 32
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.608917/523, current 332.776001/533: elapsed=167094.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.776001
last_received_ts = 332.775543
last_seq = 533
current_ts = 332.943085
current_received_ts = 332.942322
current_seq = 543
count = 33
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.776001/533, current 332.943085/543: elapsed=167080.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 332.943085
last_received_ts = 332.942322
last_seq = 543
current_ts = 333.110168
current_received_ts = 333.109589
current_seq = 553
count = 34
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 332.943085/543, current 333.110168/553: elapsed=167092.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.110168
last_received_ts = 333.109589
last_seq = 553
current_ts = 333.277252
current_received_ts = 333.276794
current_seq = 563
count = 35
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.110168/553, current 333.277252/563: elapsed=167085.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.277252
last_received_ts = 333.276794
last_seq = 563
current_ts = 333.444336
current_received_ts = 333.443878
current_seq = 573
count = 36
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.277252/563, current 333.444336/573: elapsed=167088.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.444336
last_received_ts = 333.443878
last_seq = 573
current_ts = 333.611389
current_received_ts = 333.610962
current_seq = 583
count = 37
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.444336/573, current 333.611389/583: elapsed=167053.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.611389
last_received_ts = 333.610962
last_seq = 583
current_ts = 333.778442
current_received_ts = 333.777985
current_seq = 593
count = 38
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.611389/583, current 333.778442/593: elapsed=167046.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.778442
last_received_ts = 333.777985
last_seq = 593
current_ts = 333.945496
current_received_ts = 333.945099
current_seq = 603
count = 39
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.778442/593, current 333.945496/603: elapsed=167039.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 333.945496
last_received_ts = 333.945099
last_seq = 603
current_ts = 334.112518
current_received_ts = 334.112091
current_seq = 613
count = 40
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 333.945496/603, current 334.112518/613: elapsed=167041.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.112518
last_received_ts = 334.112091
last_seq = 613
current_ts = 334.279572
current_received_ts = 334.279083
current_seq = 623
count = 41
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.112518/613, current 334.279572/623: elapsed=167047.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.279572
last_received_ts = 334.279083
last_seq = 623
current_ts = 334.446625
current_received_ts = 334.446167
current_seq = 633
count = 42
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.279572/623, current 334.446625/633: elapsed=167039.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.446625
last_received_ts = 334.446167
last_seq = 633
current_ts = 334.613617
current_received_ts = 334.613190
current_seq = 643
count = 43
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.446625/633, current 334.613617/643: elapsed=167011.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.613617
last_received_ts = 334.613190
last_seq = 643
current_ts = 334.780640
current_received_ts = 334.780151
current_seq = 653
count = 44
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.613617/643, current 334.780640/653: elapsed=167005.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.780640
last_received_ts = 334.780151
last_seq = 653
current_ts = 334.947632
current_received_ts = 334.947174
current_seq = 663
count = 45
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.780640/653, current 334.947632/663: elapsed=167002.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 334.947632
last_received_ts = 334.947174
last_seq = 663
current_ts = 335.114624
current_received_ts = 335.114166
current_seq = 673
count = 46
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 334.947632/663, current 335.114624/673: elapsed=166997.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.114624
last_received_ts = 335.114166
last_seq = 673
current_ts = 335.281616
current_received_ts = 335.281158
current_seq = 683
count = 47
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.114624/673, current 335.281616/683: elapsed=167002.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.281616
last_received_ts = 335.281158
last_seq = 683
current_ts = 335.448639
current_received_ts = 335.448151
current_seq = 693
count = 48
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.281616/683, current 335.448639/693: elapsed=167008.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.448639
last_received_ts = 335.448151
last_seq = 693
current_ts = 335.615601
current_received_ts = 335.615112
current_seq = 703
count = 49
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.448639/693, current 335.615601/703: elapsed=166975.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.615601
last_received_ts = 335.615112
last_seq = 703
current_ts = 335.782593
current_received_ts = 335.782135
current_seq = 713
count = 50
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.615601/703, current 335.782593/713: elapsed=166967.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.782593
last_received_ts = 335.782135
last_seq = 713
current_ts = 335.949554
current_received_ts = 335.949005
current_seq = 723
count = 51
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.782593/713, current 335.949554/723: elapsed=166968.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 335.949554
last_received_ts = 335.949005
last_seq = 723
current_ts = 336.116516
current_received_ts = 336.115967
current_seq = 733
count = 52
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 335.949554/723, current 336.116516/733: elapsed=166963.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.116516
last_received_ts = 336.115967
last_seq = 733
current_ts = 336.283478
current_received_ts = 336.282928
current_seq = 743
count = 53
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.116516/733, current 336.283478/743: elapsed=166967.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.283478
last_received_ts = 336.282928
last_seq = 743
current_ts = 336.450439
current_received_ts = 336.449890
current_seq = 753
count = 54
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.283478/743, current 336.450439/753: elapsed=166971.0us expected=167535.6us +- 837.7us, error 0.3%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.450439
last_received_ts = 336.449890
last_seq = 753
current_ts = 336.617401
current_received_ts = 336.616821
current_seq = 763
count = 55
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.450439/753, current 336.617401/763: elapsed=166942.0us expected=167535.6us +- 837.7us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.617401
last_received_ts = 336.616821
last_seq = 763
current_ts = 336.784332
current_received_ts = 336.783752
current_seq = 773
count = 56
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.617401/763, current 336.784332/773: elapsed=166932.0us expected=167535.6us +- 837.7us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.784332
last_received_ts = 336.783752
last_seq = 773
current_ts = 336.951263
current_received_ts = 336.950714
current_seq = 783
count = 57
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.784332/773, current 336.951263/783: elapsed=166937.0us expected=167535.6us +- 837.7us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 336.951263
last_received_ts = 336.950714
last_seq = 783
current_ts = 337.118195
current_received_ts = 337.117645
current_seq = 793
count = 58
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 336.951263/783, current 337.118195/793: elapsed=166939.0us expected=167535.6us +- 837.7us, error 0.4%
(kms_flip:4043) DEBUG: name = vblank
last_ts = 337.118195
last_received_ts = 337.117645
last_seq = 793
current_ts = 337.285126
current_received_ts = 337.284576
current_seq = 803
count = 59
seq_step = 10
(kms_flip:4043) DEBUG: vblank ts/seq: last 337.118195/793, current 337.285126/803: elapsed=166934.0us expected=167535.6us +- 837.7us, error 0.4%
(kms_flip:4043) DEBUG: expected 598, counted 600, encoder type 2
(kms_flip:4043) DEBUG: dropped frames, expected 598, counted 600, encoder type 2
(kms_flip:4043) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:4043) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:4043) igt_core-INFO: Stack trace:
(kms_flip:4043) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:4043) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:4043) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:4043) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:4043) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:4043) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4043) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4043) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (14.570s)
Subtest blocking-wf_vblank: FAIL (28.132s)
(kms_flip:4043) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246:
(kms_flip:4043) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:4043) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:4043) igt_kms-CRITICAL: DPMS property not found on 189
Dmesg
<6> [341.811402] Console: switching to colour dummy device 80x25
<6> [341.811685] [IGT] kms_flip: executing
<7> [341.817644] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1]
<7> [341.817661] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1]
<7> [341.817787] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected
<7> [341.818011] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2]
<7> [341.818018] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2]
<7> [341.818118] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected
<7> [341.818333] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3]
<7> [341.818339] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3]
<7> [341.818435] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected
<7> [341.818714] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1]
<7> [341.818722] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:189:eDP-1]
<7> [341.819414] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [341.820996] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [341.821105] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [341.821195] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [341.821304] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<5> [341.821310] [drm] DisplayID checksum invalid, remainder is 248
<5> [341.821311] [drm] DisplayID checksum invalid, remainder is 248
<7> [341.821312] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<5> [341.821314] [drm] DisplayID checksum invalid, remainder is 248
<7> [341.821315] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] ELD monitor
<5> [341.821317] [drm] DisplayID checksum invalid, remainder is 248
<7> [341.821318] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] ELD size 20, SAD count 0
<5> [341.821320] [drm] DisplayID checksum invalid, remainder is 248
<7> [341.821332] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] VRR capable: yes
<7> [341.821416] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [341.822092] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [341.822177] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<5> [341.823115] [drm] DisplayID checksum invalid, remainder is 248
<5> [341.823117] [drm] DisplayID checksum invalid, remainder is 248
<5> [341.823118] [drm] DisplayID checksum invalid, remainder is 248
<7> [341.823149] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1] probed modes:
<7> [341.823161] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [341.823188] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1]
<7> [341.823198] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1]
<7> [341.823324] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected
<7> [341.823343] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2]
<7> [341.823352] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2]
<7> [341.823480] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected
<7> [341.823498] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3]
<7> [341.823507] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3]
<7> [341.823632] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected
<6> [341.824200] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [341.825392] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [341.825839] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:227]
<7> [341.826086] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:228]
<7> [341.899356] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [341.899574] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [341.899837] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [341.900059] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [341.900270] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [341.900490] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [341.900686] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [341.900874] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [341.901054] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [341.901228] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [341.901415] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [341.901586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [341.901749] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [341.901907] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [341.902090] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [341.902263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [341.902473] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [341.902632] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [341.902777] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [341.902919] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [341.903058] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [341.903197] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [341.903335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [341.903494] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [341.903650] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [341.903786] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [341.903920] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [341.904046] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [341.904174] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [341.904299] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [341.904433] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [341.904553] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [341.904671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [341.904788] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [341.904900] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [341.905011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [341.905121] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [341.905227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [341.905330] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [341.905448] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [341.905611] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [341.905720] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [341.905882] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 4054) -> ( 0 - 0), size 4054 -> 0
<7> [341.905980] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb (4054 - 4096) -> ( 0 - 0), size 42 -> 0
<7> [341.906072] xe 0000:00:02.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> [341.906154] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [341.906235] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [341.906319] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [341.906413] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [341.906527] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [341.906633] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [341.906734] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [341.906832] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [341.906929] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [341.907026] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [341.907119] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [341.907211] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [341.907327] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: no [modeset]
<7> [341.907434] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [341.907534] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [341.907621] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [341.907708] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [341.907792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [341.907875] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [341.908009] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [341.908117] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [341.908266] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [341.925280] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [342.132383] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 0
<7> [342.132948] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [342.143863] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power off
<7> [342.144196] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [342.144368] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [342.144558] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [342.144774] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [342.196726] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [342.197082] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [342.226573] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [342.226989] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [342.227032] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [342.227545] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [342.227518] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:32:plane 1A]
<7> [342.228113] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [342.228431] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [342.228705] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [342.228977] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [342.229308] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [342.229568] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [342.229816] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [342.230053] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [342.230307] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [342.230543] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [342.230773] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [342.231003] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [342.231249] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [342.231522] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [342.231831] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [342.232080] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [342.232337] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [342.232688] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [342.232962] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [342.233295] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [342.233772] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [342.234242] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A]
<7> [342.234671] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [342.234839] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [342.235013] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [342.235148] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [342.235452] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [342.235826] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [342.235790] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [342.235912] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:189:eDP-1]
<7> [342.236125] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [342.236536] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [342.236854] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [342.237173] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [342.237519] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [342.237842] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [342.238149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [342.238517] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [342.238829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [342.239135] xe 0000:00:02.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 6329869/8388608 link 421991/524288)
<7> [342.239490] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [342.239840] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [342.240128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [342.240496] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [342.240771] xe 0000:00:02.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 2880)
<7> [342.241028] xe 0000:00:02.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 3040)
<7> [342.241293] xe 0000:00:02.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 2928)
<7> [342.241548] xe 0000:00:02.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 2960)
<7> [342.241789] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [342.242019] xe 0000:00:02.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 1800)
<7> [342.242243] xe 0000:00:02.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 1803)
<7> [342.242521] xe 0000:00:02.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 1809)
<7> [342.242780] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [342.242999] xe 0000:00:02.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 1906)
<7> [342.243202] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [342.243414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [342.243594] xe 0000:00:02.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 2880)
<7> [342.243764] xe 0000:00:02.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 3040)
<7> [342.243931] xe 0000:00:02.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 2928)
<7> [342.244089] xe 0000:00:02.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 2960)
<7> [342.244260] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [342.244435] xe 0000:00:02.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 1800)
<7> [342.244614] xe 0000:00:02.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 1803)
<7> [342.244762] xe 0000:00:02.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 1809)
<7> [342.244904] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [342.245050] xe 0000:00:02.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 1906)
<7> [342.245188] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [342.245345] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [342.245479] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [342.245609] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [342.245732] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [342.245858] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [342.245978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [342.246100] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [342.246491] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [342.246618] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [342.246767] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 4054), size 0 -> 4054
<7> [342.246865] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (4054 - 4096), size 0 -> 42
<7> [342.246958] xe 0000:00:02.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> [342.247050] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0
<7> [342.247144] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87
<7> [342.247235] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88
<7> [342.247345] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 1390840 num active planes 1
<7> [342.247468] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [342.247588] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [342.247698] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [342.247799] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [342.247898] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [342.247997] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:32:plane 1A] min cdclk (173855 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [342.248096] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (27165 kHz) > old min cdclk (0 kHz)
<7> [342.248193] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [342.248300] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [342.248400] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [342.248515] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [342.248615] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [342.248708] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [342.248796] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [342.248880] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [342.248964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [342.249044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [342.249121] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [342.249198] xe 0000:00:02.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> [342.249285] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [342.249367] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [342.249445] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [342.249524] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [342.249595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [342.249668] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [342.249743] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [342.249819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [342.249892] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [342.249964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [342.250036] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [342.250099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [342.250160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [342.250221] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1906, vmax: 2859, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [342.250302] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 2753, vmin vtotal: 1906, vmax vtotal: 2859
<7> [342.250378] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [342.250456] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [342.250531] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [342.250596] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [342.250659] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [342.250720] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [342.250782] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [342.250841] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [342.250900] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [342.250959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [342.251017] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [342.251076] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [342.251134] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [342.251192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [342.251253] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251320] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251391] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251460] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [342.251530] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [342.251589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251642] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251695] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.251749] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [342.251813] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [FB:227] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [342.251877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [342.251940] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [342.252002] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [342.252062] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [342.252126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [342.252198] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [342.252281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [342.253289] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [342.253599] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [342.253731] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [342.255209] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [342.255315] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [342.255462] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [342.255618] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [342.255690] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [342.255768] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [342.255830] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [342.255885] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [342.255938] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [342.255991] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [342.256042] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [342.256094] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [342.256146] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [342.256198] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [342.256259] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [342.256325] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [342.256393] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [342.256459] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [342.256524] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [342.256582] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [342.256732] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [342.256805] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [342.256882] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [342.258299] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power on
<7> [342.258415] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power cycle (469 ms remaining)
<7> [342.721749] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [342.726450] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [342.726939] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power on
<7> [342.727457] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [342.763430] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [342.763710] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [342.763949] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [342.764254] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [342.853706] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected)
<7> [342.853715] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected)
<7> [342.853732] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected)
<7> [343.018028] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [343.020288] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [343.020758] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [343.024496] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [343.025896] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [343.027215] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [343.029501] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [343.030835] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [343.032302] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Clock recovery OK
<7> [343.032767] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [343.034673] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [343.035029] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [343.035858] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [343.036304] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [343.036738] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [343.037188] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 13892
<7> [343.038370] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 10600448 bytes of contiguous stolen space for FBC, limit: 2
<7> [343.038738] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:32:plane 1A]
<7> [343.053267] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [343.055237] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [343.055683] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A]
<7> [343.075390] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [343.075839] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [343.076292] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [343.340242] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [343.340691] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [343.369989] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [343.370443] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [343.370923] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [345.533309] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD off
<7> [345.533730] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [347.482763] [IGT] kms_flip: finished subtest A-eDP1, FAIL
<6> [347.483547] [IGT] kms_flip: starting dynamic subtest B-eDP1
<7> [347.484432] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:225]
<7> [347.484757] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:230]
<7> [347.554932] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [347.555163] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [347.555464] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [347.555692] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [347.555935] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [347.556129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [347.556322] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [347.556510] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [347.556690] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [347.556862] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [347.557098] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [347.557276] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [347.557439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [347.557619] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [347.557795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [347.557979] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [347.558126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [347.558267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [347.558406] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [347.558547] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [347.558686] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [347.558833] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [347.558991] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [347.559122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [347.559253] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [347.559377] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [347.559502] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [347.559644] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [347.559787] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [347.559923] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [347.560060] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [347.560190] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [347.560304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [347.560417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [347.560532] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [347.560647] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [347.560758] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [347.560883] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [347.561010] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [347.561143] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [347.561313] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [347.561432] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [347.561595] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 4054) -> ( 0 - 0), size 4054 -> 0
<7> [347.561686] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb (4054 - 4096) -> ( 0 - 0), size 42 -> 0
<7> [347.561776] xe 0000:00:02.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> [347.561878] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.561965] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.562047] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.562129] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [347.562242] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [347.562350] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [347.562457] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [347.562562] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [347.562664] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [347.562769] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [347.562893] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [347.562990] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [347.563116] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: no [modeset]
<7> [347.563224] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [347.563317] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [347.563408] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [347.563497] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [347.563586] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [347.563675] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [347.563814] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [347.563917] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [347.564056] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [347.564233] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [347.564343] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [347.581376] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [347.785519] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 0
<7> [347.786147] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [347.807198] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power off
<7> [347.807638] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [347.807797] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [347.808123] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [347.808245] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [347.860247] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [347.860577] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [347.891273] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [347.891777] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [347.892142] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:32:plane 1A]
<7> [347.892170] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [347.892592] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [347.892832] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [347.893116] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [347.893397] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [347.893656] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [347.893911] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [347.894163] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [347.894462] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [347.894760] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [347.895023] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [347.895300] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [347.895644] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [347.895983] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [347.896281] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [347.896568] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [347.896904] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [347.897241] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [347.897540] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [347.897954] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [347.898097] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [347.898315] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [347.898548] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [347.898695] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [347.898962] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [347.899267] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [347.899520] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [347.899807] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A]
<7> [347.900298] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [347.900735] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [347.901032] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [347.901119] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:189:eDP-1]
<7> [347.901374] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [347.901826] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:134:pipe B] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [347.902233] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [347.902678] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:134:pipe B] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [347.903148] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:134:pipe B]
<7> [347.903566] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [347.903950] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [347.904341] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [347.904679] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [347.905011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [347.905366] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [347.905688] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [347.905982] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [347.906308] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [347.906586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [347.906840] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [347.907123] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [347.907395] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [347.907636] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [347.907867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [347.908118] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [347.908331] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [347.908537] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [347.908737] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [347.908928] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [347.909141] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [347.909352] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [347.909539] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [347.909705] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [347.909866] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [347.910087] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [347.910238] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [347.910391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [347.910543] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [347.910687] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [347.910828] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [347.910965] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [347.911132] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [347.911263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [347.911392] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [347.911520] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [347.911643] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [347.911764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [347.911883] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [347.913111] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [347.913231] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [347.913396] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 4054), size 0 -> 4054
<7> [347.913485] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (4054 - 4096), size 0 -> 42
<7> [347.913569] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [347.913648] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0
<7> [347.913723] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87
<7> [347.913805] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88
<7> [347.913877] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:134:pipe B] data rate 1390840 num active planes 1
<7> [347.913992] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [347.914086] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [347.914178] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [347.914266] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [347.914353] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [347.914439] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:84:plane 1B] min cdclk (173855 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz)
<7> [347.914528] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (27165 kHz) > old min cdclk (0 kHz)
<7> [347.914615] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [347.914697] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [347.914777] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [347.914877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [347.914980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [347.915062] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 30, dithering: 0
<7> [347.915142] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [347.915219] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [347.915293] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [347.915367] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [347.915439] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [347.915512] xe 0000:00:02.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> [347.915581] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [347.915649] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [347.915717] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [347.915785] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [347.915851] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [347.915927] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [347.915993] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [347.916064] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [347.916127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [347.916189] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [347.916249] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [347.916309] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [347.916369] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [347.916428] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1906, vmax: 2859, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [347.916488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 2753, vmin vtotal: 1906, vmax vtotal: 2859
<7> [347.916544] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [347.916600] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [347.916656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [347.916712] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [347.916769] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [347.916824] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [347.916883] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [347.916938] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [347.917005] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [347.917065] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [347.917121] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [347.917177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [347.917231] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [347.917285] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.917340] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917394] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917450] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917504] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.917559] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.917614] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917668] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.917777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.917831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:84:plane 1B] fb: [FB:225] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [347.917895] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [347.917960] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [347.917969] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [347.918037] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [347.918085] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [347.918102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [347.918164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [347.918165] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [347.918226] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [347.918257] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [347.918286] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [347.920436] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [347.920510] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [347.920586] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [347.920702] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [347.920833] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [347.920956] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [347.921030] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [347.921108] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [347.921171] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [347.921226] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [347.921281] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [347.921335] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [347.921388] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [347.921442] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [347.921495] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [347.921546] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [347.921596] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [347.921646] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [347.921695] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [347.921744] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [347.921795] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [347.921843] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [347.921996] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [347.922051] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [347.922114] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [347.922919] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power on
<7> [347.923005] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power cycle (469 ms remaining)
<7> [348.391589] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [348.393375] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [348.393807] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power on
<7> [348.394262] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [348.430317] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [348.430651] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [348.430956] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [348.431416] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [348.713177] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [348.715503] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [348.715980] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [348.719861] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [348.721315] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [348.722601] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [348.724076] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [348.725535] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [348.727017] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Clock recovery OK
<7> [348.727502] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [348.729490] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [348.729966] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [348.730978] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [348.731558] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [348.731933] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe B
<7> [348.732351] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 13892
<7> [348.733367] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 10600448 bytes of contiguous stolen space for FBC, limit: 2
<7> [348.733671] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:84:plane 1B]
<7> [348.748527] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [348.750396] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [348.750850] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:134:pipe B]
<7> [348.770961] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [348.771463] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [348.771977] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [349.035742] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [349.036282] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [349.065542] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [349.065988] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [349.066430] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [351.266568] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD off
<7> [351.266960] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [355.383337] [IGT] kms_flip: finished subtest B-eDP1, FAIL
<6> [355.384300] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [355.385262] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:229]
<7> [355.385635] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:232]
<7> [355.460489] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [355.460768] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [355.461077] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [355.461526] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [355.461663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [355.461880] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [355.462105] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [355.462305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [355.462528] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [355.462723] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [355.462895] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [355.463072] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [355.463236] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [355.463425] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [355.463604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [355.463763] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [355.463915] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [355.464074] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [355.464221] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [355.464358] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [355.464518] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [355.464676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [355.464813] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [355.464946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [355.465101] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [355.465252] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [355.465382] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [355.465512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [355.465634] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [355.465755] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [355.465874] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [355.465991] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [355.466128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [355.466263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [355.466401] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [355.466519] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [355.466631] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [355.466741] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [355.466849] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [355.466957] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [355.467079] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [355.467188] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [355.467296] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [355.467439] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [355.467555] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [355.467706] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] ddb ( 0 - 4054) -> ( 0 - 0), size 4054 -> 0
<7> [355.467808] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:129:cursor B] ddb (4054 - 4096) -> ( 0 - 0), size 42 -> 0
<7> [355.467898] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [355.467980] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] lines 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.468079] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] blocks 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.468163] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] min_ddb 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.468242] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [355.468350] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [355.468455] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [355.468559] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [355.468659] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [355.468759] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [355.468858] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [355.468955] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [355.469062] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [355.469192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:134:pipe B] enable: no [modeset]
<7> [355.469304] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [355.469407] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [355.469499] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [355.469586] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [355.469673] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [355.469755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [355.469876] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [355.470069] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [355.470191] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [355.487145] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [355.695116] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 0
<7> [355.695823] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [355.707161] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power off
<7> [355.707615] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [355.707720] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [355.708120] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [355.708188] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [355.760343] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [355.760659] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [355.774246] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [355.774756] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [355.775336] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:84:plane 1B]
<7> [355.775399] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [355.775998] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [355.776189] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [355.776565] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [355.776906] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [355.777260] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [355.777590] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [355.777916] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [355.778269] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [355.778595] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [355.778918] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [355.779262] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [355.779585] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [355.779905] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [355.780248] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [355.780572] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [355.781030] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [355.781394] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [355.781719] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [355.782227] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [355.782583] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [355.783041] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [355.783628] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [355.784196] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [355.784766] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:134:pipe B]
<7> [355.785868] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [355.786461] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [355.786546] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:189:eDP-1]
<7> [355.786788] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [355.787293] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:186:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [355.787676] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [355.788036] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [355.788363] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:186:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [355.788685] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:186:pipe C]
<7> [355.788989] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [355.789343] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [355.789619] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [355.789871] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [355.790160] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [355.790410] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [355.790645] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [355.790870] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [355.791099] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [355.791307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [355.791508] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [355.791705] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [355.791907] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [355.792155] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [355.792333] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [355.792538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [355.792724] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [355.792886] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [355.793058] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [355.793208] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [355.793359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [355.793512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [355.793658] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [355.793801] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [355.793954] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [355.794128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [355.794270] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [355.794402] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [355.794531] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [355.794654] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [355.794795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [355.794936] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [355.795070] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [355.795188] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [355.795314] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [355.795422] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [355.795531] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [355.795639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [355.795742] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:186:pipe C] fastset requirement not met, forcing full modeset
<7> [355.795946] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [355.796068] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:186:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [355.796213] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] ddb ( 0 - 0) -> ( 0 - 2006), size 0 -> 2006
<7> [355.796300] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:181:cursor C] ddb ( 0 - 0) -> (2006 - 2048), size 0 -> 42
<7> [355.796387] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [355.796472] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0
<7> [355.796553] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87
<7> [355.796630] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88
<7> [355.796705] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:186:pipe C] data rate 1390840 num active planes 1
<7> [355.796805] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [355.796900] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [355.796991] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [355.797115] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [355.797206] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [355.797314] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:136:plane 1C] min cdclk (173855 kHz) > [CRTC:186:pipe C] min cdclk (0 kHz)
<7> [355.797415] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (27165 kHz) > old min cdclk (0 kHz)
<7> [355.797519] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [355.797615] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [355.797697] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [355.797804] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:186:pipe C] enable: yes [modeset]
<7> [355.797888] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [355.797969] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [355.798083] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [355.798156] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [355.798225] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [355.798300] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [355.798381] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [355.798408] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [355.798465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [355.798545] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [355.798617] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [355.798688] xe 0000:00:02.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> [355.798755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [355.798820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [355.798885] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [355.798949] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [355.799021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [355.799089] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [355.799160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [355.799229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [355.799291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [355.799353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [355.799415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [355.799478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [355.799538] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [355.799597] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1906, vmax: 2859, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [355.799655] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 2753, vmin vtotal: 1906, vmax vtotal: 2859
<7> [355.799713] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [355.799771] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [355.799829] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [355.799887] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [355.799944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [355.800010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [355.800071] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [355.800133] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [355.800195] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [355.800252] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [355.800308] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [355.800365] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [355.800422] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [355.800478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [355.800534] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800590] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800645] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800700] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [355.800756] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [355.800813] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800870] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.800990] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [355.801047] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:136:plane 1C] fb: [FB:229] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [355.801110] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [355.801181] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [355.801240] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:plane 2C] fb: [NOFB], visible: no
<7> [355.801297] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 3C] fb: [NOFB], visible: no
<7> [355.801352] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:163:plane 4C] fb: [NOFB], visible: no
<7> [355.801408] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:172:plane 5C] fb: [NOFB], visible: no
<7> [355.801463] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:181:cursor C] fb: [NOFB], visible: no
<7> [355.804181] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [355.804310] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [355.804436] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [355.804509] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [355.804589] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [355.804652] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [355.804708] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [355.804762] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [355.804816] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [355.804870] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [355.804924] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [355.804989] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [355.805044] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [355.805101] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [355.805167] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [355.805225] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [355.805275] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [355.805325] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [355.805374] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [355.805519] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [355.805586] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [355.807003] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power on
<7> [355.807096] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power cycle (468 ms remaining)
<7> [356.275615] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [356.357333] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [356.357679] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power on
<7> [356.358068] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [356.394053] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [356.394350] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [356.394629] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [356.395013] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [356.601978] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [356.604177] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [356.604614] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [356.608449] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [356.609841] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [356.610993] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [356.612314] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [356.613573] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [356.614976] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Clock recovery OK
<7> [356.615366] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [356.617171] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [356.617571] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [356.618429] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [356.618917] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [356.619287] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [356.619764] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 13892
<7> [356.620923] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 10600448 bytes of contiguous stolen space for FBC, limit: 2
<7> [356.621289] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:136:plane 1C]
<7> [356.635808] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [356.637564] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [356.638015] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:186:pipe C]
<7> [359.149671] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD off
<7> [359.150282] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [369.954775] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [369.956519] [IGT] kms_flip: finished subtest blocking-wf_vblank, FAIL
<7> [369.965472] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:232] from all active usage due to RMFB ioctl
<7> [369.965509] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:136:plane 1C] because [FB:232] is removed
<7> [369.965561] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] ddb ( 0 - 2006) -> ( 0 - 0), size 2006 -> 0
<7> [369.965655] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [369.965728] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] lines 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [369.965798] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] blocks 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [369.965863] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] min_ddb 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [369.965927] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:186:pipe C] data rate 0 num active planes 0
<7> [369.966008] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [369.966085] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [369.966176] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [369.966252] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [369.966324] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [369.966519] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [369.976315] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [369.976545] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [369.988691] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:136:plane 1C]
<7> [370.019167] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [370.019476] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [370.019719] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [370.019949] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [370.020207] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [370.020457] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [370.020654] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [370.020837] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [370.021018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [370.021228] xe 0000:00:02.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 6329869/8388608 link 421991/524288)
<7> [370.021398] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [370.021562] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [370.021712] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [370.021858] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [370.021997] xe 0000:00:02.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 2880)
<7> [370.022179] xe 0000:00:02.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 3040)
<7> [370.022334] xe 0000:00:02.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 2928)
<7> [370.022470] xe 0000:00:02.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 2960)
<7> [370.022604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [370.022733] xe 0000:00:02.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 1800)
<7> [370.022861] xe 0000:00:02.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 1803)
<7> [370.022982] xe 0000:00:02.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 1809)
<7> [370.023148] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [370.023284] xe 0000:00:02.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 1906)
<7> [370.023421] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [370.023537] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [370.023661] xe 0000:00:02.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 2880)
<7> [370.023774] xe 0000:00:02.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 3040)
<7> [370.023885] xe 0000:00:02.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 2928)
<7> [370.023992] xe 0000:00:02.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 2960)
<7> [370.024109] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [370.024214] xe 0000:00:02.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 1800)
<7> [370.024315] xe 0000:00:02.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 1803)
<7> [370.024412] xe 0000:00:02.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 1809)
<7> [370.024508] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [370.024605] xe 0000:00:02.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 1906)
<7> [370.024696] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [370.024786] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [370.024875] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [370.024964] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [370.025051] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [370.025155] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [370.025241] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [370.025324] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [370.025406] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [370.025488] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [370.025567] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [370.025642] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [370.025719] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [370.025794] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [370.025867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [370.025941] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [370.026013] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [370.026095] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [370.026167] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [370.026239] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [370.026307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [370.026375] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [370.026442] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [370.026510] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [370.026576] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [370.026639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [370.026702] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [370.026764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [370.026825] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [370.026885] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [370.026946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [370.027008] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [370.027289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [370.027346] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [370.027407] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [370.027467] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [370.027528] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [370.027589] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [370.027649] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [370.027709] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [370.027769] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [370.027829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [370.027889] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [370.027945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [370.028002] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [370.028067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [370.028132] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:186:pipe C] fastset requirement not met, forcing full modeset
<7> [370.028255] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [370.028320] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [370.028385] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:186:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [370.028461] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 4054), size 0 -> 4054
<7> [370.028517] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (4054 - 4096), size 0 -> 42
<7> [370.028573] xe 0000:00:02.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> [370.028624] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0
<7> [370.028673] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 289, 289, 289, 265, 289, 0, 0, 63, 73, 87
<7> [370.028729] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 319, 319, 319, 293, 319, 0, 0, 64, 82, 88
<7> [370.028788] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:181:cursor C] ddb (2006 - 2048) -> ( 0 - 0), size 42 -> 0
<7> [370.028847] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 1390840 num active planes 1
<7> [370.028929] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [370.029007] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [370.029082] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [370.029152] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [370.029218] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [370.029286] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:32:plane 1A] min cdclk (173855 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [370.029359] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [370.029428] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [370.029522] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [370.029591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [370.029656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [370.029716] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [370.029776] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [370.029837] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [370.029903] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [370.029968] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [370.030032] xe 0000:00:02.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> [370.030106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [370.030181] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [370.030244] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [370.030307] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [370.030370] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [370.030427] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [370.030488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [370.030550] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [370.030611] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [370.030672] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [370.030733] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [370.030794] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [370.030851] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [370.030907] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1906, vmax: 2859, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [370.030964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 2753, vmin vtotal: 1906, vmax vtotal: 2859
<7> [370.031021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [370.031091] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [370.031155] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [370.031225] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [370.031286] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [370.031348] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [370.031408] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [370.031464] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [370.031520] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [370.031576] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [370.031638] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [370.031703] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [370.031766] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [370.031829] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [370.031890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.031952] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.032010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.032142] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [370.032224] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [370.032294] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.032364] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.032433] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [370.032493] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [370.032550] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [FB:190] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [370.032606] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [370.032661] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [370.032716] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [370.032773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [370.032832] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [370.032891] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [370.032951] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [370.033010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:186:pipe C] enable: no [modeset]
<7> [370.033079] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:136:plane 1C] fb: [NOFB], visible: no
<7> [370.033138] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:plane 2C] fb: [NOFB], visible: no
<7> [370.033196] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 3C] fb: [NOFB], visible: no
<7> [370.033250] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:163:plane 4C] fb: [NOFB], visible: no
<7> [370.033305] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:172:plane 5C] fb: [NOFB], visible: no
<7> [370.033364] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:181:cursor C] fb: [NOFB], visible: no
<7> [370.033496] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [370.033692] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [370.236084] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 0
<7> [370.236597] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [370.238997] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power off
<7> [370.239524] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [370.239516] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [370.240126] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [370.240112] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [370.292089] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [370.292387] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short
<7> [370.322547] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [370.323005] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [370.323340] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A]
<7> [370.323336] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [370.323566] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1]
<7> [370.323661] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [370.323816] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A]
<7> [370.324047] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B]
<7> [370.324244] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C]
<7> [370.324443] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2]
<7> [370.324647] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A]
<7> [370.324920] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B]
<7> [370.325120] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C]
<7> [370.325316] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3]
<7> [370.325517] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A]
<7> [370.325799] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B]
<7> [370.326010] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C]
<7> [370.326276] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [370.326693] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [370.326970] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [370.327222] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [370.328865] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power on
<7> [370.329253] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power cycle (494 ms remaining)
<7> [370.329474] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [370.329870] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [370.330239] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [370.330759] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [370.827310] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [370.909860] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [370.910308] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power on
<7> [370.910643] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [370.946739] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [370.947056] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long
<7> [370.947334] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [370.947795] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A]
<7> [371.225495] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [371.227762] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [371.228157] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [371.231625] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [371.232957] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [371.234103] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [371.235316] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [371.236429] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [371.237848] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Clock recovery OK
<7> [371.238181] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [371.239910] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [371.240261] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [371.241077] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [371.241503] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [371.241849] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [371.242219] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 13892
<7> [371.243228] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 11059200 bytes of contiguous stolen space for FBC, limit: 2
<7> [371.243570] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:32:plane 1A]
<7> [371.258462] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [371.260307] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1]
<7> [371.260754] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A]
<7> [371.263140] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [371.263656] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [371.264167] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:186:pipe C]
<7> [371.264662] xe 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [371.265879] [IGT] kms_flip: exiting, ret=98
Created at 2025-02-20 23:24:26