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

Result: Fail

boot-20250830-0301 dmesg-20250830-0301

DetailValue
Duration 10.28 seconds
Igt-Version 
IGT-Version: 2.1-g8a840cd64 (x86_64) (Linux: 6.17.0-rc3-lgci-xe-xe-3643-99f4b32d88cb9718c+ x86_64)
Out 
Using IGT_SRANDOM=1756523572 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
  2560x1600: 240 1175040 2560 2608 2640 2720 1600 1663 1669 1800 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1884 run_test()
  #3 ../tests/kms_flip.c:2153 __igt_unique____real_main2040()
  #4 ../tests/kms_flip.c:2040 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-eDP1: FAIL (10.278s)
Err 
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:3356) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:3356) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3356) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.753113
last_received_ts = 814.752808
last_seq = 31571
current_ts = 814.757324
current_received_ts = 814.756958
current_seq = 31572
count = 2006
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.757324
last_received_ts = 814.756958
last_seq = 31572
current_ts = 814.761475
current_received_ts = 814.761108
current_seq = 31573
count = 2007
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 104us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.761475
last_received_ts = 814.761108
last_seq = 31573
current_ts = 814.765625
current_received_ts = 814.765259
current_seq = 31574
count = 2008
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 105us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.765625
last_received_ts = 814.765259
last_seq = 31574
current_ts = 814.769775
current_received_ts = 814.769470
current_seq = 31575
count = 2009
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 32us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.769775
last_received_ts = 814.769470
last_seq = 31575
current_ts = 814.773987
current_received_ts = 814.773621
current_seq = 31576
count = 2010
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 98us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.773987
last_received_ts = 814.773621
last_seq = 31576
current_ts = 814.778137
current_received_ts = 814.777832
current_seq = 31577
count = 2011
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.778137
last_received_ts = 814.777832
last_seq = 31577
current_ts = 814.782288
current_received_ts = 814.781982
current_seq = 31578
count = 2012
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 66us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.782288
last_received_ts = 814.781982
last_seq = 31578
current_ts = 814.786438
current_received_ts = 814.786133
current_seq = 31579
count = 2013
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 46us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.786438
last_received_ts = 814.786133
last_seq = 31579
current_ts = 814.790649
current_received_ts = 814.790283
current_seq = 31580
count = 2014
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.790649
last_received_ts = 814.790283
last_seq = 31580
current_ts = 814.794800
current_received_ts = 814.794495
current_seq = 31581
count = 2015
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.794800
last_received_ts = 814.794495
last_seq = 31581
current_ts = 814.798950
current_received_ts = 814.798645
current_seq = 31582
count = 2016
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 39us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.798950
last_received_ts = 814.798645
last_seq = 31582
current_ts = 814.803162
current_received_ts = 814.802795
current_seq = 31583
count = 2017
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 103us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.803162
last_received_ts = 814.802795
last_seq = 31583
current_ts = 814.807312
current_received_ts = 814.807007
current_seq = 31584
count = 2018
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 107us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.807312
last_received_ts = 814.807007
last_seq = 31584
current_ts = 814.811462
current_received_ts = 814.811157
current_seq = 31585
count = 2019
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.811462
last_received_ts = 814.811157
last_seq = 31585
current_ts = 814.815613
current_received_ts = 814.815308
current_seq = 31586
count = 2020
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 104us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.815613
last_received_ts = 814.815308
last_seq = 31586
current_ts = 814.819824
current_received_ts = 814.819458
current_seq = 31587
count = 2021
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 104us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.819824
last_received_ts = 814.819458
last_seq = 31587
current_ts = 814.823975
current_received_ts = 814.823669
current_seq = 31588
count = 2022
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.823975
last_received_ts = 814.823669
last_seq = 31588
current_ts = 814.828125
current_received_ts = 814.827820
current_seq = 31589
count = 2023
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 98us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.828125
last_received_ts = 814.827820
last_seq = 31589
current_ts = 814.832275
current_received_ts = 814.831970
current_seq = 31590
count = 2024
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 57us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.832275
last_received_ts = 814.831970
last_seq = 31590
current_ts = 814.836487
current_received_ts = 814.836121
current_seq = 31591
count = 2025
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 105us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.836487
last_received_ts = 814.836121
last_seq = 31591
current_ts = 814.840637
current_received_ts = 814.840271
current_seq = 31592
count = 2026
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 103us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.840637
last_received_ts = 814.840271
last_seq = 31592
current_ts = 814.844788
current_received_ts = 814.844421
current_seq = 31593
count = 2027
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.844788
last_received_ts = 814.844421
last_seq = 31593
current_ts = 814.848999
current_received_ts = 814.848633
current_seq = 31594
count = 2028
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.848999
last_received_ts = 814.848633
last_seq = 31594
current_ts = 814.853149
current_received_ts = 814.852844
current_seq = 31595
count = 2029
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 119us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.853149
last_received_ts = 814.852844
last_seq = 31595
current_ts = 814.857300
current_received_ts = 814.856995
current_seq = 31596
count = 2030
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 141us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.857300
last_received_ts = 814.856995
last_seq = 31596
current_ts = 814.861450
current_received_ts = 814.861145
current_seq = 31597
count = 2031
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 115us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.861450
last_received_ts = 814.861145
last_seq = 31597
current_ts = 814.865662
current_received_ts = 814.865295
current_seq = 31598
count = 2032
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.865662
last_received_ts = 814.865295
last_seq = 31598
current_ts = 814.869812
current_received_ts = 814.869507
current_seq = 31599
count = 2033
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 121us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.869812
last_received_ts = 814.869507
last_seq = 31599
current_ts = 814.873962
current_received_ts = 814.873657
current_seq = 31600
count = 2034
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 109us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.873962
last_received_ts = 814.873657
last_seq = 31600
current_ts = 814.878113
current_received_ts = 814.877808
current_seq = 31601
count = 2035
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 105us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.878113
last_received_ts = 814.877808
last_seq = 31601
current_ts = 814.882324
current_received_ts = 814.881958
current_seq = 31602
count = 2036
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 61us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.882324
last_received_ts = 814.881958
last_seq = 31602
current_ts = 814.886475
current_received_ts = 814.886108
current_seq = 31603
count = 2037
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 111us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.886475
last_received_ts = 814.886108
last_seq = 31603
current_ts = 814.890625
current_received_ts = 814.890320
current_seq = 31604
count = 2038
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.890625
last_received_ts = 814.890320
last_seq = 31604
current_ts = 814.894836
current_received_ts = 814.894470
current_seq = 31605
count = 2039
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 119us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.894836
last_received_ts = 814.894470
last_seq = 31605
current_ts = 814.898987
current_received_ts = 814.898621
current_seq = 31606
count = 2040
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.898987
last_received_ts = 814.898621
last_seq = 31606
current_ts = 814.903137
current_received_ts = 814.902832
current_seq = 31607
count = 2041
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 97us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.903137
last_received_ts = 814.902832
last_seq = 31607
current_ts = 814.907288
current_received_ts = 814.906982
current_seq = 31608
count = 2042
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 103us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.907288
last_received_ts = 814.906982
last_seq = 31608
current_ts = 814.911499
current_received_ts = 814.911133
current_seq = 31609
count = 2043
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 48us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.911499
last_received_ts = 814.911133
last_seq = 31609
current_ts = 814.915649
current_received_ts = 814.915283
current_seq = 31610
count = 2044
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 102us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.915649
last_received_ts = 814.915283
last_seq = 31610
current_ts = 814.919800
current_received_ts = 814.919495
current_seq = 31611
count = 2045
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.919800
last_received_ts = 814.919495
last_seq = 31611
current_ts = 814.923950
current_received_ts = 814.923645
current_seq = 31612
count = 2046
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.923950
last_received_ts = 814.923645
last_seq = 31612
current_ts = 814.928162
current_received_ts = 814.927856
current_seq = 31613
count = 2047
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 110us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.928162
last_received_ts = 814.927856
last_seq = 31613
current_ts = 814.932312
current_received_ts = 814.932007
current_seq = 31614
count = 2048
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.932312
last_received_ts = 814.932007
last_seq = 31614
current_ts = 814.936462
current_received_ts = 814.936157
current_seq = 31615
count = 2049
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.936462
last_received_ts = 814.936157
last_seq = 31615
current_ts = 814.940674
current_received_ts = 814.940308
current_seq = 31616
count = 2050
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.940674
last_received_ts = 814.940308
last_seq = 31616
current_ts = 814.944824
current_received_ts = 814.944458
current_seq = 31617
count = 2051
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.944824
last_received_ts = 814.944458
last_seq = 31617
current_ts = 814.948975
current_received_ts = 814.948669
current_seq = 31618
count = 2052
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.948975
last_received_ts = 814.948669
last_seq = 31618
current_ts = 814.953125
current_received_ts = 814.952820
current_seq = 31619
count = 2053
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.953125
last_received_ts = 814.952820
last_seq = 31619
current_ts = 814.957336
current_received_ts = 814.957031
current_seq = 31620
count = 2054
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 102us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.957336
last_received_ts = 814.957031
last_seq = 31620
current_ts = 814.961487
current_received_ts = 814.961121
current_seq = 31621
count = 2055
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 103us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.961487
last_received_ts = 814.961121
last_seq = 31621
current_ts = 814.965637
current_received_ts = 814.965271
current_seq = 31622
count = 2056
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.965637
last_received_ts = 814.965271
last_seq = 31622
current_ts = 814.969788
current_received_ts = 814.969482
current_seq = 31623
count = 2057
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.969788
last_received_ts = 814.969482
last_seq = 31623
current_ts = 814.973999
current_received_ts = 814.973633
current_seq = 31624
count = 2058
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.973999
last_received_ts = 814.973633
last_seq = 31624
current_ts = 814.978149
current_received_ts = 814.977783
current_seq = 31625
count = 2059
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 46us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.978149
last_received_ts = 814.977783
last_seq = 31625
current_ts = 814.982300
current_received_ts = 814.981934
current_seq = 31626
count = 2060
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 58us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.982300
last_received_ts = 814.981934
last_seq = 31626
current_ts = 814.986511
current_received_ts = 814.986145
current_seq = 31627
count = 2061
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.986511
last_received_ts = 814.986145
last_seq = 31627
current_ts = 814.990662
current_received_ts = 814.990295
current_seq = 31628
count = 2062
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.990662
last_received_ts = 814.990295
last_seq = 31628
current_ts = 814.994812
current_received_ts = 814.994446
current_seq = 31629
count = 2063
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.994812
last_received_ts = 814.994446
last_seq = 31629
current_ts = 814.998962
current_received_ts = 814.998657
current_seq = 31630
count = 2064
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 814.998962
last_received_ts = 814.998657
last_seq = 31630
current_ts = 815.003174
current_received_ts = 815.002869
current_seq = 31631
count = 2065
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.003174
last_received_ts = 815.002869
last_seq = 31631
current_ts = 815.007324
current_received_ts = 815.007019
current_seq = 31632
count = 2066
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 129us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.007324
last_received_ts = 815.007019
last_seq = 31632
current_ts = 815.011475
current_received_ts = 815.011108
current_seq = 31633
count = 2067
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 114us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.011475
last_received_ts = 815.011108
last_seq = 31633
current_ts = 815.015625
current_received_ts = 815.015320
current_seq = 31634
count = 2068
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 48us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.015625
last_received_ts = 815.015320
last_seq = 31634
current_ts = 815.019836
current_received_ts = 815.019531
current_seq = 31635
count = 2069
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.019836
last_received_ts = 815.019531
last_seq = 31635
current_ts = 815.023987
current_received_ts = 815.023682
current_seq = 31636
count = 2070
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 96us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.023987
last_received_ts = 815.023682
last_seq = 31636
current_ts = 815.028137
current_received_ts = 815.027832
current_seq = 31637
count = 2071
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 108us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.028137
last_received_ts = 815.027832
last_seq = 31637
current_ts = 815.032349
current_received_ts = 815.031982
current_seq = 31638
count = 2072
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.032349
last_received_ts = 815.031982
last_seq = 31638
current_ts = 815.036499
current_received_ts = 815.036133
current_seq = 31639
count = 2073
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 110us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.036499
last_received_ts = 815.036133
last_seq = 31639
current_ts = 815.040649
current_received_ts = 815.040344
current_seq = 31640
count = 2074
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 89us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.040649
last_received_ts = 815.040344
last_seq = 31640
current_ts = 815.044800
current_received_ts = 815.044495
current_seq = 31641
count = 2075
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.044800
last_received_ts = 815.044495
last_seq = 31641
current_ts = 815.049011
current_received_ts = 815.048706
current_seq = 31642
count = 2076
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.049011
last_received_ts = 815.048706
last_seq = 31642
current_ts = 815.053162
current_received_ts = 815.052856
current_seq = 31643
count = 2077
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.053162
last_received_ts = 815.052856
last_seq = 31643
current_ts = 815.057312
current_received_ts = 815.057007
current_seq = 31644
count = 2078
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 114us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.057312
last_received_ts = 815.057007
last_seq = 31644
current_ts = 815.061462
current_received_ts = 815.061157
current_seq = 31645
count = 2079
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.061462
last_received_ts = 815.061157
last_seq = 31645
current_ts = 815.065674
current_received_ts = 815.065308
current_seq = 31646
count = 2080
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.065674
last_received_ts = 815.065308
last_seq = 31646
current_ts = 815.069824
current_received_ts = 815.069519
current_seq = 31647
count = 2081
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 110us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.069824
last_received_ts = 815.069519
last_seq = 31647
current_ts = 815.073975
current_received_ts = 815.073669
current_seq = 31648
count = 2082
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 34us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.073975
last_received_ts = 815.073669
last_seq = 31648
current_ts = 815.078186
current_received_ts = 815.077820
current_seq = 31649
count = 2083
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.078186
last_received_ts = 815.077820
last_seq = 31649
current_ts = 815.082336
current_received_ts = 815.082031
current_seq = 31650
count = 2084
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 128us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.082336
last_received_ts = 815.082031
last_seq = 31650
current_ts = 815.086487
current_received_ts = 815.086121
current_seq = 31651
count = 2085
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 104us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.086487
last_received_ts = 815.086121
last_seq = 31651
current_ts = 815.090637
current_received_ts = 815.090332
current_seq = 31652
count = 2086
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 94us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.090637
last_received_ts = 815.090332
last_seq = 31652
current_ts = 815.094849
current_received_ts = 815.094482
current_seq = 31653
count = 2087
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.094849
last_received_ts = 815.094482
last_seq = 31653
current_ts = 815.098999
current_received_ts = 815.098694
current_seq = 31654
count = 2088
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.098999
last_received_ts = 815.098694
last_seq = 31654
current_ts = 815.103149
current_received_ts = 815.102844
current_seq = 31655
count = 2089
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.103149
last_received_ts = 815.102844
last_seq = 31655
current_ts = 815.107300
current_received_ts = 815.107056
current_seq = 31656
count = 2090
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 106us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.107300
last_received_ts = 815.107056
last_seq = 31656
current_ts = 815.111511
current_received_ts = 815.111145
current_seq = 31657
count = 2091
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.111511
last_received_ts = 815.111145
last_seq = 31657
current_ts = 815.115662
current_received_ts = 815.115356
current_seq = 31658
count = 2092
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 98us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.115662
last_received_ts = 815.115356
last_seq = 31658
current_ts = 815.119812
current_received_ts = 815.119507
current_seq = 31659
count = 2093
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.119812
last_received_ts = 815.119507
last_seq = 31659
current_ts = 815.124023
current_received_ts = 815.123657
current_seq = 31660
count = 2094
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 113us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.124023
last_received_ts = 815.123657
last_seq = 31660
current_ts = 815.128174
current_received_ts = 815.127808
current_seq = 31661
count = 2095
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.128174
last_received_ts = 815.127808
last_seq = 31661
current_ts = 815.132324
current_received_ts = 815.132019
current_seq = 31662
count = 2096
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.132324
last_received_ts = 815.132019
last_seq = 31662
current_ts = 815.136475
current_received_ts = 815.136169
current_seq = 31663
count = 2097
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 54us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.136475
last_received_ts = 815.136169
last_seq = 31663
current_ts = 815.140686
current_received_ts = 815.140320
current_seq = 31664
count = 2098
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.140686
last_received_ts = 815.140320
last_seq = 31664
current_ts = 815.144836
current_received_ts = 815.144470
current_seq = 31665
count = 2099
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.144836
last_received_ts = 815.144470
last_seq = 31665
current_ts = 815.148987
current_received_ts = 815.148621
current_seq = 31666
count = 2100
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.148987
last_received_ts = 815.148621
last_seq = 31666
current_ts = 815.153137
current_received_ts = 815.152832
current_seq = 31667
count = 2101
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.153137
last_received_ts = 815.152832
last_seq = 31667
current_ts = 815.157349
current_received_ts = 815.157043
current_seq = 31668
count = 2102
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 131us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.157349
last_received_ts = 815.157043
last_seq = 31668
current_ts = 815.161499
current_received_ts = 815.161133
current_seq = 31669
count = 2103
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 33us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.161499
last_received_ts = 815.161133
last_seq = 31669
current_ts = 815.165649
current_received_ts = 815.165344
current_seq = 31670
count = 2104
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 102us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.165649
last_received_ts = 815.165344
last_seq = 31670
current_ts = 815.169861
current_received_ts = 815.169495
current_seq = 31671
count = 2105
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 103us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.169861
last_received_ts = 815.169495
last_seq = 31671
current_ts = 815.174011
current_received_ts = 815.173706
current_seq = 31672
count = 2106
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.174011
last_received_ts = 815.173706
last_seq = 31672
current_ts = 815.178162
current_received_ts = 815.177856
current_seq = 31673
count = 2107
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 104us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.178162
last_received_ts = 815.177856
last_seq = 31673
current_ts = 815.182312
current_received_ts = 815.182007
current_seq = 31674
count = 2108
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 121us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.182312
last_received_ts = 815.182007
last_seq = 31674
current_ts = 815.186523
current_received_ts = 815.186157
current_seq = 31675
count = 2109
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 98us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.186523
last_received_ts = 815.186157
last_seq = 31675
current_ts = 815.190674
current_received_ts = 815.190308
current_seq = 31676
count = 2110
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 46us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.190674
last_received_ts = 815.190308
last_seq = 31676
current_ts = 815.194824
current_received_ts = 815.194519
current_seq = 31677
count = 2111
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 90us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.194824
last_received_ts = 815.194519
last_seq = 31677
current_ts = 815.198975
current_received_ts = 815.198669
current_seq = 31678
count = 2112
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.198975
last_received_ts = 815.198669
last_seq = 31678
current_ts = 815.203186
current_received_ts = 815.202820
current_seq = 31679
count = 2113
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 95us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.203186
last_received_ts = 815.202820
last_seq = 31679
current_ts = 815.207336
current_received_ts = 815.207031
current_seq = 31680
count = 2114
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 109us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.207336
last_received_ts = 815.207031
last_seq = 31680
current_ts = 815.211487
current_received_ts = 815.211182
current_seq = 31681
count = 2115
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 108us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.211487
last_received_ts = 815.211182
last_seq = 31681
current_ts = 815.215698
current_received_ts = 815.215332
current_seq = 31682
count = 2116
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 49us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.215698
last_received_ts = 815.215332
last_seq = 31682
current_ts = 815.219849
current_received_ts = 815.219543
current_seq = 31683
count = 2117
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 47us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.219849
last_received_ts = 815.219543
last_seq = 31683
current_ts = 815.223999
current_received_ts = 815.223694
current_seq = 31684
count = 2118
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 52us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.223999
last_received_ts = 815.223694
last_seq = 31684
current_ts = 815.228149
current_received_ts = 815.227844
current_seq = 31685
count = 2119
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 128us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.228149
last_received_ts = 815.227844
last_seq = 31685
current_ts = 815.232361
current_received_ts = 815.232056
current_seq = 31686
count = 2120
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 36us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.232361
last_received_ts = 815.232056
last_seq = 31686
current_ts = 815.236511
current_received_ts = 815.236145
current_seq = 31687
count = 2121
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 50us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.236511
last_received_ts = 815.236145
last_seq = 31687
current_ts = 815.240662
current_received_ts = 815.240295
current_seq = 31688
count = 2122
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 101us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.240662
last_received_ts = 815.240295
last_seq = 31688
current_ts = 815.244812
current_received_ts = 815.244507
current_seq = 31689
count = 2123
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 100us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.244812
last_received_ts = 815.244507
last_seq = 31689
current_ts = 815.249023
current_received_ts = 815.248718
current_seq = 31690
count = 2124
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 102us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.249023
last_received_ts = 815.248718
last_seq = 31690
current_ts = 815.253174
current_received_ts = 815.252869
current_seq = 31691
count = 2125
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 99us
(kms_flip:3356) DEBUG: name = flip
last_ts = 815.253174
last_received_ts = 815.252869
last_seq = 31691
current_ts = 815.257324
current_received_ts = 815.257080
current_seq = 31692
count = 2126
seq_step = 1
(kms_flip:3356) DEBUG: Vblank took 766us
(kms_flip:3356) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:3356) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3356) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3356) igt_core-INFO: Stack trace:
(kms_flip:3356) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3356) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:3356) igt_core-INFO:   #2 ../tests/kms_flip.c:1884 run_test()
(kms_flip:3356) igt_core-INFO:   #3 ../tests/kms_flip.c:2153 __igt_unique____real_main2040()
(kms_flip:3356) igt_core-INFO:   #4 ../tests/kms_flip.c:2040 main()
(kms_flip:3356) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3356) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3356) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (10.278s)
Dmesg
<6> [823.790874] Console: switching to colour dummy device 80x25
<6> [823.791143] [IGT] kms_flip: executing
<7> [823.796826] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:214:DP-1]
<7> [823.796834] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:214:DP-1]
<7> [823.797016] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> dp-alt) pin assignment: E max lanes: 4
<7> [823.797139] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [823.798429] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PICA_TC
<7> [823.801816] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 01 03 80 02 02 06 00 00 00 81
<7> [823.801846] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 03 80 02 02 06 00 00 00 81
<7> [823.802953] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:214:DP-1][ENCODER:213:DDI TC1/PHY TC1][DPRX] LTTPR common capabilities: 20 1e 80 aa 04 00 01 03
<7> [823.805318] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:214:DP-1][ENCODER:213:DDI TC1/PHY TC1][LTTPR 1] PHY capabilities: 04 00 00
<7> [823.806218] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: LTTPR 1: OUI 98-4f-ee dev-ID HBR HW-rev 10.2 SW-rev 2.11 quirks 0x0000
<7> [823.808797] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 01 03 80 02 02 06 00 00 00 81
<7> [823.808814] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 03 80 02 02 06 00 00 00 81
<7> [823.809571] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DP sink: OUI 00-0c-e7 dev-ID HW-rev 0.0 SW-rev 0.0 quirks 0x0020
<7> [823.811798] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [ENCODER:213:DDI TC1/PHY TC1] MST support: port: yes, sink: MST, modparam: yes -> enable: MST
<7> [823.819215] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 01 21 03 7f 3b 07 01 00 00 1f 0e 11 08 00 00 00
<7> [823.820061] xe 0000:00:02.0: [drm:intel_dp_get_dsc_sink_cap [xe]] FEC CAPABILITY: ff
<7> [823.820952] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000, 1000000, 2000000
<7> [823.821054] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 270000, 540000, 810000, 1000000
<7> [823.821154] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 270000, 540000, 810000, 1000000
<7> [823.821252] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:214:DP-1] disconnected
<7> [823.821574] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:226:DP-2]
<7> [823.821579] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:226:DP-2]
<7> [823.821685] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PICA_TC
<7> [823.821787] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [823.821941] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [823.822036] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:226:DP-2] disconnected
<7> [823.822248] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:234:DP-3]
<7> [823.822252] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:234:DP-3]
<7> [823.822355] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [823.822445] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:234:DP-3] disconnected
<7> [823.822720] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:205:eDP-1]
<7> [823.822724] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:205:eDP-1]
<7> [823.823209] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 01 21 00 76 0a 02 01 80 00 01 06 66 08 00 00 00
<7> [823.823508] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [823.823609] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000
<7> [823.823704] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000
<7> [823.823821] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:205:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 240 Hz
<7> [823.823829] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:205:eDP-1] DisplayID extension version 0x20, primary use 0x02
<7> [823.823833] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:205:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [823.823837] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:205:eDP-1] ELD monitor NE160QDM-NZ7
<7> [823.823840] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:205:eDP-1] ELD size 32, SAD count 0
<7> [823.823860] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] VRR capable: yes
<7> [823.823945] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [823.824014] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [823.824555] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:205:eDP-1] probed modes:
<7> [823.824559] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 240 1175040 2560 2608 2640 2720 1600 1663 1669 1800 0x48 0xa
<7> [823.824562] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 293760 2560 2608 2640 2720 1600 1663 1669 1800 0x48 0xa
<7> [823.824574] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:214:DP-1]
<7> [823.824577] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:214:DP-1]
<7> [823.824737] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [823.826061] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PICA_TC
<7> [823.829399] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 01 03 80 02 02 06 00 00 00 81
<7> [823.829411] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 03 80 02 02 06 00 00 00 81
<7> [823.830150] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:214:DP-1][ENCODER:213:DDI TC1/PHY TC1][DPRX] LTTPR common capabilities: 20 1e 80 aa 04 00 01 03
<7> [823.832386] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:214:DP-1][ENCODER:213:DDI TC1/PHY TC1][LTTPR 1] PHY capabilities: 04 00 00
<7> [823.833213] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: LTTPR 1: OUI 98-4f-ee dev-ID HBR HW-rev 10.2 SW-rev 2.11 quirks 0x0000
<7> [823.835792] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 01 03 80 02 02 06 00 00 00 81
<7> [823.835803] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 03 80 02 02 06 00 00 00 81
<7> [823.837053] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DP sink: OUI 00-0c-e7 dev-ID HW-rev 0.0 SW-rev 0.0 quirks 0x0020
<7> [823.839110] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [ENCODER:213:DDI TC1/PHY TC1] MST support: port: yes, sink: MST, modparam: yes -> enable: MST
<7> [823.846224] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 01 21 03 7f 3b 07 01 00 00 1f 0e 11 08 00 00 00
<7> [823.847029] xe 0000:00:02.0: [drm:intel_dp_get_dsc_sink_cap [xe]] FEC CAPABILITY: ff
<7> [823.847833] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000, 1000000, 2000000
<7> [823.847913] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 270000, 540000, 810000, 1000000
<7> [823.847984] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 270000, 540000, 810000, 1000000
<7> [823.848055] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:214:DP-1] disconnected
<7> [823.848066] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:226:DP-2]
<7> [823.848070] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:226:DP-2]
<7> [823.848166] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:226:DP-2] disconnected
<7> [823.848174] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:234:DP-3]
<7> [823.848177] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:234:DP-3]
<7> [823.848271] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:234:DP-3] disconnected
<6> [823.851561] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [823.852649] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [823.853106] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:243]
<7> [823.853333] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:244]
<7> [823.913746] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [823.913893] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [823.914038] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [823.914141] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [823.914241] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [823.914336] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 8214124/8388608 link 912680/524288, found tu 0, data 0/0 link 0/0)
<7> [823.914455] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [823.914556] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [823.914653] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2560, found 0)
<7> [823.914745] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2720, found 0)
<7> [823.914834] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2560, found 0)
<7> [823.914925] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2720, found 0)
<7> [823.915011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2608, found 0)
<7> [823.915097] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2640, found 0)
<7> [823.915184] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1600, found 0)
<7> [823.915270] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1600, found 0)
<7> [823.915373] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1663, found 0)
<7> [823.915473] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1669, found 0)
<7> [823.915564] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1800, found 0)
<7> [823.915654] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1800, found 0)
<7> [823.915744] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2560, found 0)
<7> [823.915836] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2720, found 0)
<7> [823.915926] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2560, found 0)
<7> [823.916019] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2720, found 0)
<7> [823.916110] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2608, found 0)
<7> [823.916201] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2640, found 0)
<7> [823.916284] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1600, found 0)
<7> [823.916378] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1600, found 0)
<7> [823.916471] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1663, found 0)
<7> [823.916561] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1669, found 0)
<7> [823.916644] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1800, found 0)
<7> [823.916733] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1800, found 0)
<7> [823.916821] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [823.916908] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [823.916996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [823.917085] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [823.917176] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 18, found 0)
<7> [823.917268] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 1175040, found 0)
<7> [823.917463] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 1175040, found 0)
<7> [823.917553] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 675000, found 0)
<7> [823.917639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in min_voltage_level (expected 1, found 0)
<7> [823.917726] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [823.917872] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [823.917952] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [823.918052] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 3942) -> ( 0 - 0), size 3942 -> 0
<7> [823.918125] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb (3942 - 4096) -> ( 0 - 0), size 154 -> 0
<7> [823.918192] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [823.918258] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 4, 46, 46, 46, 43, 46, 0, 0, 0, 9, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [823.918323] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 85, 967, 967, 967, 904, 967, 0, 0, 99, 190, 204 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [823.918409] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 95,1065,1065,1065, 996,1065, 0, 0, 100, 210, 210 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [823.918509] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 0 num active planes 0
<7> [823.918615] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [823.918716] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [823.918818] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [823.918914] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [823.919007] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [823.919105] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via crawling and squashing
<7> [823.919199] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [823.919295] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [823.919427] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: no [modeset]
<7> [823.919529] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [823.919623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [823.919721] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [823.919828] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [823.919923] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [823.920014] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [823.920162] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [823.920690] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [823.920869] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling Panel Replay
<7> [823.925654] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [823.925897] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [823.926017] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [823.929459] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PICA_TC
<7> [823.929630] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [824.129399] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 0
<7> [824.129768] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [824.134729] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power off
<7> [824.134972] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [824.135205] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [824.195582] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [824.195914] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [824.196266] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [824.196513] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DDI TC1/PHY TC1]
<7> [824.196716] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST A]
<7> [824.196909] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST B]
<7> [824.197102] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DP-MST C]
<7> [824.197296] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:225:DDI TC2/PHY TC2]
<7> [824.197516] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST A]
<7> [824.197712] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST B]
<7> [824.197902] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:229:DP-MST C]
<7> [824.198096] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:233:DDI TC3/PHY TC3]
<7> [824.198289] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST A]
<7> [824.198509] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST B]
<7> [824.198698] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:237:DP-MST C]
<7> [824.198894] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [824.199137] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 3 (mbus joined: no)
<7> [824.199359] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [824.199569] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [824.199940] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [824.200311] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [824.200592] 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> [824.200913] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [824.201196] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [824.202000] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [824.202488] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [824.202832] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [824.202919] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:205:eDP-1]
<7> [824.203135] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:205:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [824.203529] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:204:DDI A/PHY A][CRTC:88:pipe A] DP link limits: pixel clock 1175040 kHz DSC off max lanes 4 max rate 675000 max pipe_bpp 30 max link_bpp 30.0000
<7> [824.203806] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 675000 bpp input 18 compressed 0.0000 link rate required 2643840 available 2700000
<7> [824.204097] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] hw max bpp: 30, pipe bpp: 18, dithering: 1
<7> [824.204424] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:204:DDI A/PHY A] [CRTC:88:pipe A]
<7> [824.204734] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [824.205021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [824.205302] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [824.205614] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [824.205897] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [824.206166] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [824.206462] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2560)
<7> [824.206735] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2720)
<7> [824.207003] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2560)
<7> [824.207269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2720)
<7> [824.207560] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2608)
<7> [824.207832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2640)
<7> [824.208096] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1600)
<7> [824.208377] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1600)
<7> [824.208626] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1663)
<7> [824.208872] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1669)
<7> [824.209112] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1800)
<7> [824.209375] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1800)
<7> [824.209619] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2560)
<7> [824.209841] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2720)
<7> [824.210069] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2560)
<7> [824.210293] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2720)
<7> [824.210545] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2608)
<7> [824.210766] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2640)
<7> [824.210968] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1600)
<7> [824.211168] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1600)
<7> [824.211383] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1663)
<7> [824.211575] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1669)
<7> [824.211745] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1800)
<7> [824.211912] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1800)
<7> [824.212089] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [824.212260] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [824.212527] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [824.212718] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [824.212890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 18)
<7> [824.213051] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 0, found 675000)
<7> [824.213208] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in min_voltage_level (expected 0, found 1)
<7> [824.213375] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [824.213658] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [824.213798] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [824.213961] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 3942), size 0 -> 3942
<7> [824.214093] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb ( 0 - 0) -> (3942 - 4096), size 0 -> 154
<7> [824.214199] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [824.214302] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 46, 46, 46, 43, 46, 0, 0, 0, 9, 0
<7> [824.214421] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 82, 933, 933, 933, 872, 933, 0, 0, 177, 184, 198
<7> [824.214539] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 163,1054,1054,1054, 973,1054, 0, 0, 178, 325, 325
<7> [824.214676] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 4700160 num active planes 1
<7> [824.214839] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 4936 qgv_peak_bw: 38400
<7> [824.214986] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 4936 qgv_peak_bw: 38400
<7> [824.215121] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 4936 qgv_peak_bw: 38400
<7> [824.215239] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 4936 qgv_peak_bw: 38400
<7> [824.215371] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 4936
<7> [824.215516] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:33:plane 1A] min cdclk (587520 kHz) > [CRTC:88:pipe A] min cdclk (0 kHz)
<7> [824.215630] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (91800 kHz) > old min cdclk (0 kHz)
<7> [824.215769] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via crawling and squashing
<7> [824.215901] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 612000 kHz, actual 612000 kHz
<7> [824.216030] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 3, actual 3
<7> [824.216177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: yes [modeset]
<7> [824.216289] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [824.216405] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 18, dithering: 1
<7> [824.216505] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [824.216597] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [824.216686] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [824.216775] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [824.216864] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 8214124, data_n: 8388608, link_m: 912680, link_n: 524288, tu: 64
<7> [824.216957] 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> [824.217047] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [824.217139] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [824.217232] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: enabled, panel replay: enabled, selective fetch: enabled
<7> [824.217327] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [824.217433] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [824.217532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 6, length 16
<7> [824.217623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [824.217711] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [824.217799] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [824.217886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [824.217974] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [824.218065] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [824.218157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [824.218250] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1800, vmax: 1800, flipline: 1800, pipeline full: 0, guardband: 200 vsync start: 137, vsync end: 131
<7> [824.218357] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1600, vmax vblank: 1600, vmin vtotal: 1800, vmax vtotal: 1800
<7> [824.218453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2560x1600": 240 1175040 2560 2608 2640 2720 1600 1663 1669 1800 0x48 0xa
<7> [824.218543] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2560x1600": 240 1175040 2560 2608 2640 2720 1600 1663 1669 1800 0x48 0xa
<7> [824.218635] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=1175040, hd=2560 hb=2560-2720 hs=2608-2640 ht=2720, vd=1600 vb=1600-1800 vs=1663-1669 vt=1800, flags=0xa
<7> [824.218731] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2560x1600": 240 1175040 2560 2608 2640 2720 1600 1663 1669 1800 0x40 0xa
<7> [824.218818] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=1175040, hd=2560 hb=2560-2720 hs=2608-2640 ht=2720, vd=1600 vb=1600-1800 vs=1663-1669 vt=1800, flags=0xa
<7> [824.218905] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 675000, pipe src: 2560x1600+0+0, pixel rate 1175040
<7> [824.218993] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 19, ips linetime: 0
<7> [824.219081] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [824.219173] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [824.219264] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [824.219369] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0xa4cb8, dpll_md: 0xb42110, fp0: 0x13e, fp1: 0x1000000
<7> [824.219467] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [824.219557] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [824.219648] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [824.219746] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.219836] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.219923] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.220010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [824.220096] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [824.220182] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.220272] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.220380] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [824.220387] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [824.220476] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [824.220533] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [824.220565] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:243] 2560x1600 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [824.220647] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [824.220657] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [824.220752] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2560.000000x1600.000000+0.000000+0.000000 dst: 2560x1600+0+0
<7> [824.220854] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [824.220943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [824.221030] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [824.221117] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [824.221204] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [824.221603] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [824.221867] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [824.222038] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [824.222208] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 612000 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 3
<7> [824.222352] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 3 (mbus joined: no)
<7> [824.222453] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=1530
<7> [824.222550] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [824.222624] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DDI TC1/PHY TC1]
<7> [824.222697] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST A]
<7> [824.222766] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST B]
<7> [824.222835] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DP-MST C]
<7> [824.222903] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:225:DDI TC2/PHY TC2]
<7> [824.222972] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST A]
<7> [824.223039] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST B]
<7> [824.223106] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:229:DP-MST C]
<7> [824.223174] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:233:DDI TC3/PHY TC3]
<7> [824.223241] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST A]
<7> [824.223308] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST B]
<7> [824.223392] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:237:DP-MST C]
<7> [824.223466] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [824.223629] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [824.223704] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 6 (mbus joined: yes)
<7> [824.223819] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [824.225181] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD on
<7> [824.225270] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power cycle (471 ms remaining)
<7> [824.705382] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [824.735784] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [824.736121] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000068
<7> [824.736443] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 panel power wasn't enabled
<7> [824.865446] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [824.865501] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (dp-alt -> disconnected) pin assignment: E max lanes: 4
<7> [824.865471] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [824.938660] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power on
<7> [824.938947] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power cycle (0 ms remaining)
<7> [824.939221] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000068
<7> [824.949536] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [824.949835] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power on
<7> [824.950125] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x0000006b
<7> [825.159523] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [825.163743] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e c4 c1 00 00 01 c0 02 00 02 00 08 09 84
<7> [825.165420] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [825.166275] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07
<7> [825.167404] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [825.168502] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [825.169544] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Clock recovery OK
<7> [825.169839] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [825.187625] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [825.187969] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Link Training passed at link rate = 675000, lane count = 4
<7> [825.188793] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [825.189343] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [825.189695] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [825.190131] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 13892
<7> [825.190692] xe 0000:00:02.0: [drm:i915_hotplug_work_func [xe]] running encoder hotplug functions
<7> [825.193600] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling Panel Replay
<7> [825.195494] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [825.195888] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [827.745101] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD off
<7> [827.745594] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<3> [827.817279] usb usb4-port1: Cannot enable. Maybe the USB cable is bad?
<4> [827.817346] usb usb4-port1: config error
<3> [831.865274] usb usb4-port1: Cannot enable. Maybe the USB cable is bad?
<4> [831.865367] usb usb4-port1: config error
<7> [834.083482] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [834.083802] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [834.084064] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [834.131814] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2025-08-30 08:28:59