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

Result: Fail

i915_display_info15 igt_runner15 results15.json results15-xe-load.json i915_display_info_post_exec15 boot15 dmesg15

DetailValue
Duration 3.80 seconds
Hostname 
shard-lnl-1
Igt-Version 
IGT-Version: 2.2-g6d82d231c (x86_64) (Linux: 6.18.0-rc1-lgci-xe-xe-3930-aaaff197c9186f495+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 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 C-eDP1: FAIL (3.796s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (26.243s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2247 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
(kms_flip:2256) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2256) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2256) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:2256) DEBUG: Vblank took 66us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.575180
last_received_ts = 153.574524
last_seq = 818
current_ts = 153.591843
current_received_ts = 153.591049
current_seq = 819
count = 31
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 61us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.591843
last_received_ts = 153.591049
last_seq = 819
current_ts = 153.608490
current_received_ts = 153.607864
current_seq = 820
count = 32
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 61us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.608490
last_received_ts = 153.607864
last_seq = 820
current_ts = 153.625153
current_received_ts = 153.624557
current_seq = 821
count = 33
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 101us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.625153
last_received_ts = 153.624557
last_seq = 821
current_ts = 153.641830
current_received_ts = 153.641052
current_seq = 822
count = 34
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 131us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.641830
last_received_ts = 153.641052
last_seq = 822
current_ts = 153.658493
current_received_ts = 153.657700
current_seq = 823
count = 35
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 124us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.658493
last_received_ts = 153.657700
last_seq = 823
current_ts = 153.675156
current_received_ts = 153.674484
current_seq = 824
count = 36
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 54us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.675156
last_received_ts = 153.674484
last_seq = 824
current_ts = 153.691818
current_received_ts = 153.691132
current_seq = 825
count = 37
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 62us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.691818
last_received_ts = 153.691132
last_seq = 825
current_ts = 153.708481
current_received_ts = 153.707794
current_seq = 826
count = 38
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 52us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.708481
last_received_ts = 153.707794
last_seq = 826
current_ts = 153.725143
current_received_ts = 153.724426
current_seq = 827
count = 39
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.725143
last_received_ts = 153.724426
last_seq = 827
current_ts = 153.741806
current_received_ts = 153.741104
current_seq = 828
count = 40
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.741806
last_received_ts = 153.741104
last_seq = 828
current_ts = 153.758469
current_received_ts = 153.757782
current_seq = 829
count = 41
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.758469
last_received_ts = 153.757782
last_seq = 829
current_ts = 153.775131
current_received_ts = 153.774460
current_seq = 830
count = 42
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 49us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.775131
last_received_ts = 153.774460
last_seq = 830
current_ts = 153.791809
current_received_ts = 153.791077
current_seq = 831
count = 43
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.791809
last_received_ts = 153.791077
last_seq = 831
current_ts = 153.808456
current_received_ts = 153.807770
current_seq = 832
count = 44
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.808456
last_received_ts = 153.807770
last_seq = 832
current_ts = 153.825134
current_received_ts = 153.824432
current_seq = 833
count = 45
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 94us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.825134
last_received_ts = 153.824432
last_seq = 833
current_ts = 153.841797
current_received_ts = 153.841064
current_seq = 834
count = 46
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.841797
last_received_ts = 153.841064
last_seq = 834
current_ts = 153.858459
current_received_ts = 153.857742
current_seq = 835
count = 47
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.858459
last_received_ts = 153.857742
last_seq = 835
current_ts = 153.875122
current_received_ts = 153.874435
current_seq = 836
count = 48
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 36us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.875122
last_received_ts = 153.874435
last_seq = 836
current_ts = 153.891785
current_received_ts = 153.891068
current_seq = 837
count = 49
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.891785
last_received_ts = 153.891068
last_seq = 837
current_ts = 153.908447
current_received_ts = 153.907776
current_seq = 838
count = 50
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.908447
last_received_ts = 153.907776
last_seq = 838
current_ts = 153.925125
current_received_ts = 153.924423
current_seq = 839
count = 51
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.925125
last_received_ts = 153.924423
last_seq = 839
current_ts = 153.941772
current_received_ts = 153.941071
current_seq = 840
count = 52
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.941772
last_received_ts = 153.941071
last_seq = 840
current_ts = 153.958450
current_received_ts = 153.957748
current_seq = 841
count = 53
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 105us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.958450
last_received_ts = 153.957748
last_seq = 841
current_ts = 153.975113
current_received_ts = 153.974380
current_seq = 842
count = 54
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.975113
last_received_ts = 153.974380
last_seq = 842
current_ts = 153.991776
current_received_ts = 153.991058
current_seq = 843
count = 55
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 41us
(kms_flip:2256) DEBUG: name = flip
last_ts = 153.991776
last_received_ts = 153.991058
last_seq = 843
current_ts = 154.008438
current_received_ts = 154.007751
current_seq = 844
count = 56
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 39us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.008438
last_received_ts = 154.007751
last_seq = 844
current_ts = 154.025101
current_received_ts = 154.024384
current_seq = 845
count = 57
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 37us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.025101
last_received_ts = 154.024384
last_seq = 845
current_ts = 154.041763
current_received_ts = 154.041046
current_seq = 846
count = 58
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 39us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.041763
last_received_ts = 154.041046
last_seq = 846
current_ts = 154.058426
current_received_ts = 154.057724
current_seq = 847
count = 59
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.058426
last_received_ts = 154.057724
last_seq = 847
current_ts = 154.075089
current_received_ts = 154.074387
current_seq = 848
count = 60
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 39us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.075089
last_received_ts = 154.074387
last_seq = 848
current_ts = 154.091751
current_received_ts = 154.091049
current_seq = 849
count = 61
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 33us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.091751
last_received_ts = 154.091049
last_seq = 849
current_ts = 154.108414
current_received_ts = 154.107712
current_seq = 850
count = 62
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.108414
last_received_ts = 154.107712
last_seq = 850
current_ts = 154.125092
current_received_ts = 154.124390
current_seq = 851
count = 63
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 108us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.125092
last_received_ts = 154.124390
last_seq = 851
current_ts = 154.141754
current_received_ts = 154.140945
current_seq = 852
count = 64
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 55us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.141754
last_received_ts = 154.140945
last_seq = 852
current_ts = 154.158401
current_received_ts = 154.157730
current_seq = 853
count = 65
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.158401
last_received_ts = 154.157730
last_seq = 853
current_ts = 154.175079
current_received_ts = 154.174393
current_seq = 854
count = 66
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 49us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.175079
last_received_ts = 154.174393
last_seq = 854
current_ts = 154.191742
current_received_ts = 154.191055
current_seq = 855
count = 67
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.191742
last_received_ts = 154.191055
last_seq = 855
current_ts = 154.208405
current_received_ts = 154.207703
current_seq = 856
count = 68
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.208405
last_received_ts = 154.207703
last_seq = 856
current_ts = 154.225067
current_received_ts = 154.224365
current_seq = 857
count = 69
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 57us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.225067
last_received_ts = 154.224365
last_seq = 857
current_ts = 154.241730
current_received_ts = 154.241058
current_seq = 858
count = 70
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 34us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.241730
last_received_ts = 154.241058
last_seq = 858
current_ts = 154.258392
current_received_ts = 154.257706
current_seq = 859
count = 71
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 52us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.258392
last_received_ts = 154.257706
last_seq = 859
current_ts = 154.275055
current_received_ts = 154.274368
current_seq = 860
count = 72
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 53us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.275055
last_received_ts = 154.274368
last_seq = 860
current_ts = 154.291733
current_received_ts = 154.291031
current_seq = 861
count = 73
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 48us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.291733
last_received_ts = 154.291031
last_seq = 861
current_ts = 154.308395
current_received_ts = 154.307709
current_seq = 862
count = 74
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 110us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.308395
last_received_ts = 154.307709
last_seq = 862
current_ts = 154.325058
current_received_ts = 154.324341
current_seq = 863
count = 75
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 37us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.325058
last_received_ts = 154.324341
last_seq = 863
current_ts = 154.341721
current_received_ts = 154.341003
current_seq = 864
count = 76
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.341721
last_received_ts = 154.341003
last_seq = 864
current_ts = 154.358383
current_received_ts = 154.357712
current_seq = 865
count = 77
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 114us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.358383
last_received_ts = 154.357712
last_seq = 865
current_ts = 154.375046
current_received_ts = 154.374329
current_seq = 866
count = 78
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.375046
last_received_ts = 154.374329
last_seq = 866
current_ts = 154.391708
current_received_ts = 154.391022
current_seq = 867
count = 79
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.391708
last_received_ts = 154.391022
last_seq = 867
current_ts = 154.408371
current_received_ts = 154.407669
current_seq = 868
count = 80
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.408371
last_received_ts = 154.407669
last_seq = 868
current_ts = 154.425034
current_received_ts = 154.424316
current_seq = 869
count = 81
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.425034
last_received_ts = 154.424316
last_seq = 869
current_ts = 154.441696
current_received_ts = 154.441040
current_seq = 870
count = 82
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 41us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.441696
last_received_ts = 154.441040
last_seq = 870
current_ts = 154.458359
current_received_ts = 154.457687
current_seq = 871
count = 83
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.458359
last_received_ts = 154.457687
last_seq = 871
current_ts = 154.475021
current_received_ts = 154.474335
current_seq = 872
count = 84
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 56us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.475021
last_received_ts = 154.474335
last_seq = 872
current_ts = 154.491699
current_received_ts = 154.491028
current_seq = 873
count = 85
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.491699
last_received_ts = 154.491028
last_seq = 873
current_ts = 154.508362
current_received_ts = 154.507660
current_seq = 874
count = 86
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.508362
last_received_ts = 154.507660
last_seq = 874
current_ts = 154.525024
current_received_ts = 154.524292
current_seq = 875
count = 87
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.525024
last_received_ts = 154.524292
last_seq = 875
current_ts = 154.541687
current_received_ts = 154.540985
current_seq = 876
count = 88
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 61us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.541687
last_received_ts = 154.540985
last_seq = 876
current_ts = 154.558350
current_received_ts = 154.557663
current_seq = 877
count = 89
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.558350
last_received_ts = 154.557663
last_seq = 877
current_ts = 154.575012
current_received_ts = 154.574295
current_seq = 878
count = 90
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 35us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.575012
last_received_ts = 154.574295
last_seq = 878
current_ts = 154.591675
current_received_ts = 154.590973
current_seq = 879
count = 91
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 58us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.591675
last_received_ts = 154.590973
last_seq = 879
current_ts = 154.608353
current_received_ts = 154.607666
current_seq = 880
count = 92
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 103us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.608353
last_received_ts = 154.607666
last_seq = 880
current_ts = 154.625015
current_received_ts = 154.624283
current_seq = 881
count = 93
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.625015
last_received_ts = 154.624283
last_seq = 881
current_ts = 154.641678
current_received_ts = 154.640976
current_seq = 882
count = 94
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 57us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.641678
last_received_ts = 154.640976
last_seq = 882
current_ts = 154.658340
current_received_ts = 154.657639
current_seq = 883
count = 95
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.658340
last_received_ts = 154.657639
last_seq = 883
current_ts = 154.675003
current_received_ts = 154.674271
current_seq = 884
count = 96
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 41us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.675003
last_received_ts = 154.674271
last_seq = 884
current_ts = 154.691681
current_received_ts = 154.690994
current_seq = 885
count = 97
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 56us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.691681
last_received_ts = 154.690994
last_seq = 885
current_ts = 154.708344
current_received_ts = 154.707626
current_seq = 886
count = 98
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.708344
last_received_ts = 154.707626
last_seq = 886
current_ts = 154.725006
current_received_ts = 154.724289
current_seq = 887
count = 99
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 62us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.725006
last_received_ts = 154.724289
last_seq = 887
current_ts = 154.741669
current_received_ts = 154.740967
current_seq = 888
count = 100
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 50us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.741669
last_received_ts = 154.740967
last_seq = 888
current_ts = 154.758331
current_received_ts = 154.757629
current_seq = 889
count = 101
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.758331
last_received_ts = 154.757629
last_seq = 889
current_ts = 154.775009
current_received_ts = 154.774277
current_seq = 890
count = 102
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 48us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.775009
last_received_ts = 154.774277
last_seq = 890
current_ts = 154.791672
current_received_ts = 154.790955
current_seq = 891
count = 103
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 50us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.791672
last_received_ts = 154.790955
last_seq = 891
current_ts = 154.808334
current_received_ts = 154.807648
current_seq = 892
count = 104
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.808334
last_received_ts = 154.807648
last_seq = 892
current_ts = 154.824997
current_received_ts = 154.824295
current_seq = 893
count = 105
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 38us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.824997
last_received_ts = 154.824295
last_seq = 893
current_ts = 154.841660
current_received_ts = 154.840973
current_seq = 894
count = 106
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 65us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.841660
last_received_ts = 154.840973
last_seq = 894
current_ts = 154.858337
current_received_ts = 154.857635
current_seq = 895
count = 107
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.858337
last_received_ts = 154.857635
last_seq = 895
current_ts = 154.875000
current_received_ts = 154.874268
current_seq = 896
count = 108
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 48us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.875000
last_received_ts = 154.874268
last_seq = 896
current_ts = 154.891663
current_received_ts = 154.890945
current_seq = 897
count = 109
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 51us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.891663
last_received_ts = 154.890945
last_seq = 897
current_ts = 154.908325
current_received_ts = 154.907639
current_seq = 898
count = 110
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.908325
last_received_ts = 154.907639
last_seq = 898
current_ts = 154.924988
current_received_ts = 154.924271
current_seq = 899
count = 111
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.924988
last_received_ts = 154.924271
last_seq = 899
current_ts = 154.941650
current_received_ts = 154.940964
current_seq = 900
count = 112
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 56us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.941650
last_received_ts = 154.940964
last_seq = 900
current_ts = 154.958328
current_received_ts = 154.957626
current_seq = 901
count = 113
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 57us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.958328
last_received_ts = 154.957626
last_seq = 901
current_ts = 154.974991
current_received_ts = 154.974304
current_seq = 902
count = 114
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 56us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.974991
last_received_ts = 154.974304
last_seq = 902
current_ts = 154.991653
current_received_ts = 154.990967
current_seq = 903
count = 115
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 66us
(kms_flip:2256) DEBUG: name = flip
last_ts = 154.991653
last_received_ts = 154.990967
last_seq = 903
current_ts = 155.008316
current_received_ts = 155.007614
current_seq = 904
count = 116
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 41us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.008316
last_received_ts = 155.007614
last_seq = 904
current_ts = 155.024994
current_received_ts = 155.024277
current_seq = 905
count = 117
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.024994
last_received_ts = 155.024277
last_seq = 905
current_ts = 155.041656
current_received_ts = 155.040970
current_seq = 906
count = 118
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 63us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.041656
last_received_ts = 155.040970
last_seq = 906
current_ts = 155.058319
current_received_ts = 155.057632
current_seq = 907
count = 119
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.058319
last_received_ts = 155.057632
last_seq = 907
current_ts = 155.074982
current_received_ts = 155.074249
current_seq = 908
count = 120
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.074982
last_received_ts = 155.074249
last_seq = 908
current_ts = 155.091644
current_received_ts = 155.090958
current_seq = 909
count = 121
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 36us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.091644
last_received_ts = 155.090958
last_seq = 909
current_ts = 155.108322
current_received_ts = 155.107605
current_seq = 910
count = 122
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.108322
last_received_ts = 155.107605
last_seq = 910
current_ts = 155.124985
current_received_ts = 155.124252
current_seq = 911
count = 123
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.124985
last_received_ts = 155.124252
last_seq = 911
current_ts = 155.141647
current_received_ts = 155.140945
current_seq = 912
count = 124
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.141647
last_received_ts = 155.140945
last_seq = 912
current_ts = 155.158310
current_received_ts = 155.157639
current_seq = 913
count = 125
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.158310
last_received_ts = 155.157639
last_seq = 913
current_ts = 155.174988
current_received_ts = 155.174271
current_seq = 914
count = 126
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.174988
last_received_ts = 155.174271
last_seq = 914
current_ts = 155.191650
current_received_ts = 155.190918
current_seq = 915
count = 127
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 46us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.191650
last_received_ts = 155.190918
last_seq = 915
current_ts = 155.208313
current_received_ts = 155.207611
current_seq = 916
count = 128
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 70us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.208313
last_received_ts = 155.207611
last_seq = 916
current_ts = 155.224976
current_received_ts = 155.224258
current_seq = 917
count = 129
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 48us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.224976
last_received_ts = 155.224258
last_seq = 917
current_ts = 155.241638
current_received_ts = 155.240936
current_seq = 918
count = 130
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.241638
last_received_ts = 155.240936
last_seq = 918
current_ts = 155.258316
current_received_ts = 155.257660
current_seq = 919
count = 131
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 55us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.258316
last_received_ts = 155.257660
last_seq = 919
current_ts = 155.274979
current_received_ts = 155.274261
current_seq = 920
count = 132
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.274979
last_received_ts = 155.274261
last_seq = 920
current_ts = 155.291641
current_received_ts = 155.290955
current_seq = 921
count = 133
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 53us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.291641
last_received_ts = 155.290955
last_seq = 921
current_ts = 155.308304
current_received_ts = 155.307602
current_seq = 922
count = 134
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 51us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.308304
last_received_ts = 155.307602
last_seq = 922
current_ts = 155.324966
current_received_ts = 155.324234
current_seq = 923
count = 135
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 59us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.324966
last_received_ts = 155.324234
last_seq = 923
current_ts = 155.341644
current_received_ts = 155.340958
current_seq = 924
count = 136
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.341644
last_received_ts = 155.340958
last_seq = 924
current_ts = 155.358307
current_received_ts = 155.357620
current_seq = 925
count = 137
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 54us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.358307
last_received_ts = 155.357620
last_seq = 925
current_ts = 155.374969
current_received_ts = 155.374252
current_seq = 926
count = 138
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 42us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.374969
last_received_ts = 155.374252
last_seq = 926
current_ts = 155.391632
current_received_ts = 155.390961
current_seq = 927
count = 139
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 67us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.391632
last_received_ts = 155.390961
last_seq = 927
current_ts = 155.408295
current_received_ts = 155.407578
current_seq = 928
count = 140
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 38us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.408295
last_received_ts = 155.407578
last_seq = 928
current_ts = 155.424957
current_received_ts = 155.424240
current_seq = 929
count = 141
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.424957
last_received_ts = 155.424240
last_seq = 929
current_ts = 155.441635
current_received_ts = 155.440933
current_seq = 930
count = 142
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 43us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.441635
last_received_ts = 155.440933
last_seq = 930
current_ts = 155.458298
current_received_ts = 155.457611
current_seq = 931
count = 143
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 57us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.458298
last_received_ts = 155.457611
last_seq = 931
current_ts = 155.474960
current_received_ts = 155.474243
current_seq = 932
count = 144
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 48us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.474960
last_received_ts = 155.474243
last_seq = 932
current_ts = 155.491623
current_received_ts = 155.490921
current_seq = 933
count = 145
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 114us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.491623
last_received_ts = 155.490921
last_seq = 933
current_ts = 155.508286
current_received_ts = 155.507584
current_seq = 934
count = 146
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 44us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.508286
last_received_ts = 155.507584
last_seq = 934
current_ts = 155.524963
current_received_ts = 155.524231
current_seq = 935
count = 147
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.524963
last_received_ts = 155.524231
last_seq = 935
current_ts = 155.541626
current_received_ts = 155.540939
current_seq = 936
count = 148
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 41us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.541626
last_received_ts = 155.540939
last_seq = 936
current_ts = 155.558289
current_received_ts = 155.557602
current_seq = 937
count = 149
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 45us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.558289
last_received_ts = 155.557602
last_seq = 937
current_ts = 155.574951
current_received_ts = 155.574234
current_seq = 938
count = 150
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 47us
(kms_flip:2256) DEBUG: name = flip
last_ts = 155.574951
last_received_ts = 155.574234
last_seq = 938
current_ts = 155.591629
current_received_ts = 155.590942
current_seq = 939
count = 151
seq_step = 1
(kms_flip:2256) DEBUG: Vblank took 986us
(kms_flip:2256) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2256) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2256) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2256) igt_core-INFO: Stack trace:
(kms_flip:2256) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2256) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:2256) igt_core-INFO:   #2 ../tests/kms_flip.c:1884 run_test()
(kms_flip:2256) igt_core-INFO:   #3 ../tests/kms_flip.c:2153 __igt_unique____real_main2040()
(kms_flip:2256) igt_core-INFO:   #4 ../tests/kms_flip.c:2040 main()
(kms_flip:2256) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2256) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2256) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (3.796s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (26.243s)
(kms_flip:2256) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2247:
(kms_flip:2256) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2256) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2256) igt_kms-CRITICAL: DPMS property not found on 205
Dmesg
<6> [151.884193] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [151.886449] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:241]
<7> [151.887697] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:243]
<7> [151.899700] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [151.899977] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [151.900214] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [151.967947] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [151.968117] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [151.968317] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [151.968540] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [151.968636] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [151.968834] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [151.968952] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [151.969063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [151.969170] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145: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> [151.969278] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [151.969387] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [151.969495] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [151.969604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [151.969721] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [151.969832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [151.969942] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [151.970053] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [151.970166] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [151.970280] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [151.970395] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [151.970511] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [151.970629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [151.970753] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [151.970872] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [151.970989] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [151.971111] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [151.971232] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [151.971354] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [151.971477] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [151.971603] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [151.971736] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [151.971862] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [151.971991] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [151.972122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [151.972253] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [151.972383] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [151.972519] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [151.972660] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [151.972800] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [151.972936] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [151.973077] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [151.973220] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [151.973365] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [151.973619] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [151.973752] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [151.973907] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [151.974047] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:145:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [151.974213] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [151.974365] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [151.974513] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [151.974708] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: no [modeset]
<7> [151.974861] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [NOFB], visible: no
<7> [151.975004] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [151.975141] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [151.975276] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [151.975407] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [151.975539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [151.975871] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [151.976016] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [152.182684] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 0
<7> [152.183283] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [152.203330] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power off
<7> [152.203627] 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> [152.203951] 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> [152.256447] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [152.256878] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - short
<7> [152.264472] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [152.264767] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [152.264834] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [152.265210] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [152.265451] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DDI TC1/PHY TC1]
<7> [152.265708] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST A]
<7> [152.265926] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST B]
<7> [152.266142] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DP-MST C]
<7> [152.266358] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:225:DDI TC2/PHY TC2]
<7> [152.266572] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST A]
<7> [152.266804] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST B]
<7> [152.267018] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:229:DP-MST C]
<7> [152.267234] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:233:DDI TC3/PHY TC3]
<7> [152.267451] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST A]
<7> [152.267678] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST B]
<7> [152.267892] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:237:DP-MST C]
<7> [152.268105] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [152.268360] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [152.268580] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [152.268803] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [152.269175] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [152.269492] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [152.269827] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [152.270084] 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> [152.270411] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [152.270663] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [152.271370] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [152.271791] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [152.271870] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:205:eDP-1]
<7> [152.272070] 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> [152.272420] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:204:DDI A/PHY A][CRTC:202: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> [152.272719] 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> [152.272998] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [152.273238] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [152.273529] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:204:DDI A/PHY A] [CRTC:202:pipe C]
<7> [152.273843] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [152.274129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [152.274408] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [152.274702] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [152.274978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202: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> [152.275258] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [152.275538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [152.275833] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [152.276112] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [152.276386] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [152.276678] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [152.276951] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [152.277224] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [152.277498] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [152.277764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [152.278019] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [152.278269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [152.278504] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [152.278830] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [152.279055] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [152.279273] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [152.279489] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [152.279708] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [152.279916] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [152.280111] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [152.280304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [152.280493] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [152.280687] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [152.280873] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [152.281048] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [152.281222] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [152.281389] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [152.281554] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [152.281730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [152.281898] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [152.282056] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [152.282209] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [152.282358] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [152.282505] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] fastset requirement not met, forcing full modeset
<7> [152.282701] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [152.282819] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:202:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [152.282976] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:147:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [152.283077] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:197:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [152.283172] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147: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> [152.283267] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [152.283360] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [152.283451] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [152.283567] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:202:pipe C] data rate 1390840 num active planes 1
<7> [152.283723] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [152.283865] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [152.283998] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [152.284125] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [152.284249] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [152.284398] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:202:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [152.284523] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [152.284640] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [152.284769] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [152.284911] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:202:pipe C] enable: yes [modeset]
<7> [152.285025] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [152.285133] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [152.285237] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [152.285338] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [152.285437] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [152.285535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [152.285633] 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> [152.285743] 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> [152.285845] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [152.285942] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [152.286040] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [152.286137] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [152.286234] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [152.286333] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [152.286433] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [152.286532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [152.286629] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [152.286735] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [152.286835] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [152.286933] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [152.287030] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 set context latency: 0
<7> [152.287128] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [152.287229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 1800, vmin vtotal: 1906, vmax vtotal: 1906
<7> [152.287327] 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> [152.287426] 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> [152.287525] 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> [152.287623] 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> [152.287730] 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> [152.287830] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [152.287931] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [152.288030] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [152.288127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [152.288224] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [152.288321] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [152.288420] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [152.288519] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [152.288617] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [152.288732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.288740] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [152.288831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.288899] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [152.288928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.289025] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [152.289022] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [152.289125] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [152.289164] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [152.289222] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.289320] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.289419] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [152.289517] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [152.289614] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:147:plane 1C] fb: [FB:241] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [152.289732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [152.289832] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [152.289932] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:157:plane 2C] fb: [NOFB], visible: no
<7> [152.290031] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:167:plane 3C] fb: [NOFB], visible: no
<7> [152.290130] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:177:plane 4C] fb: [NOFB], visible: no
<7> [152.290227] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:187:plane 5C] fb: [NOFB], visible: no
<7> [152.290324] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:cursor C] fb: [NOFB], visible: no
<7> [152.290897] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [152.291014] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [152.291268] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [152.291439] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [152.291586] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [152.291765] 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> [152.291887] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [152.291997] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [152.292103] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [152.292184] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DDI TC1/PHY TC1]
<7> [152.292261] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST A]
<7> [152.292336] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST B]
<7> [152.292412] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DP-MST C]
<7> [152.292486] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:225:DDI TC2/PHY TC2]
<7> [152.292563] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST A]
<7> [152.292638] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST B]
<7> [152.292727] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:229:DP-MST C]
<7> [152.292805] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:233:DDI TC3/PHY TC3]
<7> [152.292894] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST A]
<7> [152.292943] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [152.292978] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST B]
<7> [152.293051] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [152.293061] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:237:DP-MST C]
<7> [152.293129] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [152.293146] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [152.293210] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [152.293330] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [152.293469] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [152.294673] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power on
<7> [152.294773] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power cycle (470 ms remaining)
<7> [152.766739] 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> [152.807132] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [152.807402] 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> [152.807700] 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: 0x00000063
<7> [152.844129] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [152.844422] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [152.844665] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [152.844991] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [153.019627] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [153.021785] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD on
<7> [153.022088] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [153.024327] 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> [153.025621] 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> [153.026422] 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 05
<7> [153.027540] 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> [153.028631] 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> [153.029671] 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> [153.029979] 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> [153.031368] 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> [153.031689] 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 = 432000, lane count = 4
<7> [153.032520] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [153.032998] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [153.033318] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [153.033735] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 4518
<7> [153.035298] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [153.035616] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:147:plane 1C]
<7> [153.049972] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [153.051607] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [153.052075] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:202:pipe C]
<7> [155.599645] 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> [155.600096] 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
<6> [155.680273] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [155.683613] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [155.695681] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:243] from all active usage due to RMFB ioctl
<7> [155.696388] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:147:plane 1C] because [FB:243] is removed
<7> [155.696532] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:147:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [155.696970] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147: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> [155.697308] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [155.697665] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [155.697977] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [155.698500] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:202:pipe C] data rate 0 num active planes 0
<7> [155.699484] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [155.699936] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [155.700336] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [155.700790] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [155.701166] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [155.703301] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [155.704021] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD on
<7> [155.704414] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [155.716018] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:147:plane 1C]
<7> [155.739918] 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> [155.740349] 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 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [155.740760] 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> [155.741188] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [155.741605] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:204:DDI A/PHY A] [CRTC:88:pipe A]
<7> [155.742029] 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> [155.742382] 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> [155.742746] 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> [155.743131] 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> [155.743422] 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 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [155.743735] 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> [155.744062] 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> [155.744332] 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 2880)
<7> [155.744780] 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 3040)
<7> [155.745011] 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 2880)
<7> [155.745266] 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 3040)
<7> [155.745483] 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 2928)
<7> [155.745710] 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 2960)
<7> [155.745933] 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 1800)
<7> [155.746119] 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 1800)
<7> [155.746303] 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 1803)
<7> [155.746501] 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 1809)
<7> [155.746714] 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 1906)
<7> [155.746912] 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 1906)
<7> [155.747096] 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 2880)
<7> [155.747263] 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 3040)
<7> [155.747415] 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 2880)
<7> [155.747569] 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 3040)
<7> [155.747714] 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 2928)
<7> [155.747857] 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 2960)
<7> [155.747995] 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 1800)
<7> [155.748128] 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 1800)
<7> [155.748259] 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 1803)
<7> [155.748392] 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 1809)
<7> [155.748523] 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 1906)
<7> [155.748803] 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 1906)
<7> [155.748961] 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> [155.749086] 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> [155.749207] 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> [155.749326] 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 30)
<7> [155.749441] 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 0, found 347710)
<7> [155.749564] 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 0, found 347710)
<7> [155.749673] 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 432000)
<7> [155.749782] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [155.749890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [155.749995] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [155.750094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [155.750193] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [155.750288] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202: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> [155.750383] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [155.750475] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [155.750585] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [155.750676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [155.750765] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [155.750854] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [155.750937] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [155.751021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [155.751103] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [155.751184] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [155.751267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [155.751350] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [155.751429] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [155.751509] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [155.751604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [155.751693] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [155.751779] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [155.751864] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [155.751946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [155.752018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [155.752089] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [155.752161] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [155.752231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [155.752297] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [155.752361] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [155.752426] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [155.752490] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [155.752574] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [155.752663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [155.752753] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [155.752840] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [155.752911] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [155.752977] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [155.753043] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] fastset requirement not met, forcing full modeset
<7> [155.753167] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [155.753232] 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 0x4 -> 0x1
<7> [155.753298] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:202:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [155.753365] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [155.753430] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [155.753488] 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> [155.753555] 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 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [155.753627] 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 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [155.753706] 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 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [155.753781] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:197:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [155.753870] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 1390840 num active planes 1
<7> [155.753953] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [155.754030] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [155.754105] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [155.754181] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [155.754255] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [155.754330] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:88:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [155.754406] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:202:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [155.754504] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: yes [modeset]
<7> [155.754613] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [155.754714] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [155.754810] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [155.754895] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [155.754972] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [155.755044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [155.755115] 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> [155.755188] 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> [155.755260] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [155.755331] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [155.755403] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [155.755475] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [155.755557] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [155.755653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [155.755747] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [155.755840] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [155.755913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [155.755983] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [155.756053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [155.756122] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [155.756192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 set context latency: 0
<7> [155.756261] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97
<7> [155.756330] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 1800, vmin vtotal: 1906, vmax vtotal: 1906
<7> [155.756397] 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> [155.756466] 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> [155.756541] 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> [155.756629] 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> [155.756717] 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> [155.756805] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710
<7> [155.756886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [155.756952] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [155.757020] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [155.757090] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [155.757160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [155.757230] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [155.757299] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [155.757367] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [155.757435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.757502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.757598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.757685] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [155.757773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [155.757858] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.757928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.757995] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [155.758060] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [155.758126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:206] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [155.758193] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [155.758259] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [155.758326] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [155.758391] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [155.758457] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [155.758523] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [155.758619] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [155.758706] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:202:pipe C] enable: no [modeset]
<7> [155.758792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:147:plane 1C] fb: [NOFB], visible: no
<7> [155.758870] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:157:plane 2C] fb: [NOFB], visible: no
<7> [155.758943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:167:plane 3C] fb: [NOFB], visible: no
<7> [155.759012] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:177:plane 4C] fb: [NOFB], visible: no
<7> [155.759079] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:187:plane 5C] fb: [NOFB], visible: no
<7> [155.759147] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:cursor C] fb: [NOFB], visible: no
<7> [155.759275] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [155.759511] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [155.759623] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [155.966602] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 0
<7> [155.967068] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [155.987174] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power off
<7> [155.987454] 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> [155.987732] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [155.987782] 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> [155.988035] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - short
<7> [156.040156] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [156.040440] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - short
<7> [156.042954] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [156.043841] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [156.043941] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [156.044315] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [156.044327] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [156.044580] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DDI TC1/PHY TC1]
<7> [156.044827] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST A]
<7> [156.045062] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST B]
<7> [156.045326] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DP-MST C]
<7> [156.045564] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:225:DDI TC2/PHY TC2]
<7> [156.045784] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST A]
<7> [156.045998] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST B]
<7> [156.046209] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:229:DP-MST C]
<7> [156.046447] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:233:DDI TC3/PHY TC3]
<7> [156.046689] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST A]
<7> [156.046895] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST B]
<7> [156.047098] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:237:DP-MST C]
<7> [156.047324] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [156.047738] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [156.047959] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [156.048229] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [156.049563] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power on
<7> [156.049788] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power cycle (494 ms remaining)
<7> [156.076875] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [156.077170] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [156.077465] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [156.077789] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [156.550710] 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> [156.651071] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [156.651387] 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> [156.651712] 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: 0x00000063
<7> [156.688162] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [156.688463] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [156.688721] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [156.689062] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [156.865727] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [156.867911] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD on
<7> [156.868293] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [156.870729] 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> [156.872125] 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> [156.873228] 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 05
<7> [156.874705] 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> [156.876065] 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> [156.877351] 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> [156.877876] 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> [156.879481] 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> [156.879930] 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 = 432000, lane count = 4
<7> [156.880718] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [156.881299] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [156.881761] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [156.882242] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 4518
<7> [156.898391] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [156.900335] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [156.900787] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [156.903070] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [156.903526] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [156.904009] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:202:pipe C]
<7> [156.904924] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [156.905353] [IGT] kms_flip: exiting, ret=98
Created at 2025-10-17 01:00:48