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

Result: Fail

i915_display_info13 igt_runner13 results13.json results13-xe-load.json guc_logs13.tar i915_display_info_post_exec13 serial_data13 boot13 dmesg13

DetailValue
Duration 3.87 seconds
Hostname 
shard-lnl-2
Igt-Version 
IGT-Version: 2.4-g53cdcbd04 (x86_64) (Linux: 7.1.0-rc6-lgci-xe-xe-pw-167940v1-debug+ 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:2106 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (3.871s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (8.065s)
Stack trace:
  #0 ../lib/igt_core.c:2106 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2354 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2992 igt_atexit_handler()
  #3 ./stdlib/exit.c:109 __run_exit_handlers()
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2447 igt_exit()
  #6 [main+0x3f]
  #7 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #8 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
[188.322108] (kms_flip:3984) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[188.322292] (kms_flip:3984) CRITICAL: Failed assertion: end - start < 500
[188.322373] (kms_flip:3984) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[186.302689] (kms_flip:3984) DEBUG: Vblank took 53us
[186.319374] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.834305
last_received_ts = 187.833542
last_seq = 96
current_ts = 187.850967
current_received_ts = 187.850281
current_seq = 97
count = 34
seq_step = 1
[186.319414] (kms_flip:3984) DEBUG: Vblank took 37us
[186.336032] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.850967
last_received_ts = 187.850281
last_seq = 97
current_ts = 187.867630
current_received_ts = 187.866943
current_seq = 98
count = 35
seq_step = 1
[186.336109] (kms_flip:3984) DEBUG: Vblank took 72us
[186.352719] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.867630
last_received_ts = 187.866943
last_seq = 98
current_ts = 187.884293
current_received_ts = 187.883636
current_seq = 99
count = 36
seq_step = 1
[186.352781] (kms_flip:3984) DEBUG: Vblank took 58us
[186.369364] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.884293
last_received_ts = 187.883636
last_seq = 99
current_ts = 187.900970
current_received_ts = 187.900284
current_seq = 100
count = 37
seq_step = 1
[186.369422] (kms_flip:3984) DEBUG: Vblank took 56us
[186.385996] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.900970
last_received_ts = 187.900284
last_seq = 100
current_ts = 187.917633
current_received_ts = 187.916916
current_seq = 101
count = 38
seq_step = 1
[186.386049] (kms_flip:3984) DEBUG: Vblank took 51us
[186.402683] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.917633
last_received_ts = 187.916916
last_seq = 101
current_ts = 187.934296
current_received_ts = 187.933594
current_seq = 102
count = 39
seq_step = 1
[186.402740] (kms_flip:3984) DEBUG: Vblank took 55us
[186.419368] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.934296
last_received_ts = 187.933594
last_seq = 102
current_ts = 187.950958
current_received_ts = 187.950287
current_seq = 103
count = 40
seq_step = 1
[186.419429] (kms_flip:3984) DEBUG: Vblank took 57us
[186.436022] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.950958
last_received_ts = 187.950287
last_seq = 103
current_ts = 187.967621
current_received_ts = 187.966949
current_seq = 104
count = 41
seq_step = 1
[186.436063] (kms_flip:3984) DEBUG: Vblank took 38us
[186.452705] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.967621
last_received_ts = 187.966949
last_seq = 104
current_ts = 187.984299
current_received_ts = 187.983627
current_seq = 105
count = 42
seq_step = 1
[186.452835] (kms_flip:3984) DEBUG: Vblank took 127us
[186.469235] (kms_flip:3984) DEBUG: name = flip
last_ts = 187.984299
last_received_ts = 187.983627
last_seq = 105
current_ts = 188.000961
current_received_ts = 188.000153
current_seq = 106
count = 43
seq_step = 1
[186.469282] (kms_flip:3984) DEBUG: Vblank took 46us
[186.486007] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.000961
last_received_ts = 188.000153
last_seq = 106
current_ts = 188.017624
current_received_ts = 188.016937
current_seq = 107
count = 44
seq_step = 1
[186.486055] (kms_flip:3984) DEBUG: Vblank took 46us
[186.502688] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.017624
last_received_ts = 188.016937
last_seq = 107
current_ts = 188.034286
current_received_ts = 188.033615
current_seq = 108
count = 45
seq_step = 1
[186.502743] (kms_flip:3984) DEBUG: Vblank took 51us
[186.519347] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.034286
last_received_ts = 188.033615
last_seq = 108
current_ts = 188.050949
current_received_ts = 188.050278
current_seq = 109
count = 46
seq_step = 1
[186.519390] (kms_flip:3984) DEBUG: Vblank took 39us
[186.535900] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.050949
last_received_ts = 188.050278
last_seq = 109
current_ts = 188.067612
current_received_ts = 188.066833
current_seq = 110
count = 47
seq_step = 1
[186.535951] (kms_flip:3984) DEBUG: Vblank took 49us
[186.552677] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.067612
last_received_ts = 188.066833
last_seq = 110
current_ts = 188.084290
current_received_ts = 188.083603
current_seq = 111
count = 48
seq_step = 1
[186.552739] (kms_flip:3984) DEBUG: Vblank took 58us
[186.569274] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.084290
last_received_ts = 188.083603
last_seq = 111
current_ts = 188.100937
current_received_ts = 188.100204
current_seq = 112
count = 49
seq_step = 1
[186.569336] (kms_flip:3984) DEBUG: Vblank took 59us
[186.586016] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.100937
last_received_ts = 188.100204
last_seq = 112
current_ts = 188.117615
current_received_ts = 188.116943
current_seq = 113
count = 50
seq_step = 1
[186.586091] (kms_flip:3984) DEBUG: Vblank took 72us
[186.602563] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.117615
last_received_ts = 188.116943
last_seq = 113
current_ts = 188.134277
current_received_ts = 188.133499
current_seq = 114
count = 51
seq_step = 1
[186.602616] (kms_flip:3984) DEBUG: Vblank took 50us
[186.619323] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.134277
last_received_ts = 188.133499
last_seq = 114
current_ts = 188.150940
current_received_ts = 188.150253
current_seq = 115
count = 52
seq_step = 1
[186.619381] (kms_flip:3984) DEBUG: Vblank took 55us
[186.636005] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.150940
last_received_ts = 188.150253
last_seq = 115
current_ts = 188.167603
current_received_ts = 188.166931
current_seq = 116
count = 53
seq_step = 1
[186.636080] (kms_flip:3984) DEBUG: Vblank took 72us
[186.652657] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.167603
last_received_ts = 188.166931
last_seq = 116
current_ts = 188.184265
current_received_ts = 188.183594
current_seq = 117
count = 54
seq_step = 1
[186.652710] (kms_flip:3984) DEBUG: Vblank took 50us
[186.669239] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.184265
last_received_ts = 188.183594
last_seq = 117
current_ts = 188.200943
current_received_ts = 188.200180
current_seq = 118
count = 55
seq_step = 1
[186.669293] (kms_flip:3984) DEBUG: Vblank took 51us
[186.685916] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.200943
last_received_ts = 188.200180
last_seq = 118
current_ts = 188.217606
current_received_ts = 188.216843
current_seq = 119
count = 56
seq_step = 1
[186.685982] (kms_flip:3984) DEBUG: Vblank took 62us
[186.702554] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.217606
last_received_ts = 188.216843
last_seq = 119
current_ts = 188.234268
current_received_ts = 188.233490
current_seq = 120
count = 57
seq_step = 1
[186.702606] (kms_flip:3984) DEBUG: Vblank took 49us
[186.719225] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.234268
last_received_ts = 188.233490
last_seq = 120
current_ts = 188.250931
current_received_ts = 188.250168
current_seq = 121
count = 58
seq_step = 1
[186.719273] (kms_flip:3984) DEBUG: Vblank took 44us
[186.735871] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.250931
last_received_ts = 188.250168
last_seq = 121
current_ts = 188.267593
current_received_ts = 188.266815
current_seq = 122
count = 59
seq_step = 1
[186.735927] (kms_flip:3984) DEBUG: Vblank took 53us
[186.752643] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.267593
last_received_ts = 188.266815
last_seq = 122
current_ts = 188.284256
current_received_ts = 188.283585
current_seq = 123
count = 60
seq_step = 1
[186.752693] (kms_flip:3984) DEBUG: Vblank took 47us
[186.769318] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.284256
last_received_ts = 188.283585
last_seq = 123
current_ts = 188.300934
current_received_ts = 188.300262
current_seq = 124
count = 61
seq_step = 1
[186.769380] (kms_flip:3984) DEBUG: Vblank took 59us
[186.785984] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.300934
last_received_ts = 188.300262
last_seq = 124
current_ts = 188.317596
current_received_ts = 188.316925
current_seq = 125
count = 62
seq_step = 1
[186.786042] (kms_flip:3984) DEBUG: Vblank took 56us
[186.802650] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.317596
last_received_ts = 188.316925
last_seq = 125
current_ts = 188.334259
current_received_ts = 188.333588
current_seq = 126
count = 63
seq_step = 1
[186.802703] (kms_flip:3984) DEBUG: Vblank took 50us
[186.819311] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.334259
last_received_ts = 188.333588
last_seq = 126
current_ts = 188.350922
current_received_ts = 188.350250
current_seq = 127
count = 64
seq_step = 1
[186.819367] (kms_flip:3984) DEBUG: Vblank took 52us
[186.836004] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.350922
last_received_ts = 188.350250
last_seq = 127
current_ts = 188.367584
current_received_ts = 188.366943
current_seq = 128
count = 65
seq_step = 1
[186.836071] (kms_flip:3984) DEBUG: Vblank took 64us
[186.852628] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.367584
last_received_ts = 188.366943
last_seq = 128
current_ts = 188.384247
current_received_ts = 188.383575
current_seq = 129
count = 66
seq_step = 1
[186.852679] (kms_flip:3984) DEBUG: Vblank took 48us
[186.869295] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.384247
last_received_ts = 188.383575
last_seq = 129
current_ts = 188.400925
current_received_ts = 188.400238
current_seq = 130
count = 67
seq_step = 1
[186.869355] (kms_flip:3984) DEBUG: Vblank took 56us
[186.885943] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.400925
last_received_ts = 188.400238
last_seq = 130
current_ts = 188.417587
current_received_ts = 188.416885
current_seq = 131
count = 68
seq_step = 1
[186.886000] (kms_flip:3984) DEBUG: Vblank took 54us
[186.902511] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.417587
last_received_ts = 188.416885
last_seq = 131
current_ts = 188.434250
current_received_ts = 188.433456
current_seq = 132
count = 69
seq_step = 1
[186.902567] (kms_flip:3984) DEBUG: Vblank took 53us
[186.919301] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.434250
last_received_ts = 188.433456
last_seq = 132
current_ts = 188.450912
current_received_ts = 188.450256
current_seq = 133
count = 70
seq_step = 1
[186.919357] (kms_flip:3984) DEBUG: Vblank took 52us
[186.935943] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.450912
last_received_ts = 188.450256
last_seq = 133
current_ts = 188.467575
current_received_ts = 188.466888
current_seq = 134
count = 71
seq_step = 1
[186.935999] (kms_flip:3984) DEBUG: Vblank took 54us
[186.952592] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.467575
last_received_ts = 188.466888
last_seq = 134
current_ts = 188.484238
current_received_ts = 188.483551
current_seq = 135
count = 72
seq_step = 1
[186.952635] (kms_flip:3984) DEBUG: Vblank took 39us
[186.969279] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.484238
last_received_ts = 188.483551
last_seq = 135
current_ts = 188.500916
current_received_ts = 188.500229
current_seq = 136
count = 73
seq_step = 1
[186.969332] (kms_flip:3984) DEBUG: Vblank took 49us
[186.985942] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.500916
last_received_ts = 188.500229
last_seq = 136
current_ts = 188.517578
current_received_ts = 188.516891
current_seq = 137
count = 74
seq_step = 1
[186.986016] (kms_flip:3984) DEBUG: Vblank took 70us
[187.002619] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.517578
last_received_ts = 188.516891
last_seq = 137
current_ts = 188.534241
current_received_ts = 188.533569
current_seq = 138
count = 75
seq_step = 1
[187.002675] (kms_flip:3984) DEBUG: Vblank took 52us
[187.019237] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.534241
last_received_ts = 188.533569
last_seq = 138
current_ts = 188.550903
current_received_ts = 188.550201
current_seq = 139
count = 76
seq_step = 1
[187.019276] (kms_flip:3984) DEBUG: Vblank took 36us
[187.035830] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.550903
last_received_ts = 188.550201
last_seq = 139
current_ts = 188.567566
current_received_ts = 188.566788
current_seq = 140
count = 77
seq_step = 1
[187.035877] (kms_flip:3984) DEBUG: Vblank took 44us
[187.052595] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.567566
last_received_ts = 188.566788
last_seq = 140
current_ts = 188.584229
current_received_ts = 188.583557
current_seq = 141
count = 78
seq_step = 1
[187.052709] (kms_flip:3984) DEBUG: Vblank took 110us
[187.069255] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.584229
last_received_ts = 188.583557
last_seq = 141
current_ts = 188.600906
current_received_ts = 188.600220
current_seq = 142
count = 79
seq_step = 1
[187.069305] (kms_flip:3984) DEBUG: Vblank took 47us
[187.085838] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.600906
last_received_ts = 188.600220
last_seq = 142
current_ts = 188.617554
current_received_ts = 188.616806
current_seq = 143
count = 80
seq_step = 1
[187.085899] (kms_flip:3984) DEBUG: Vblank took 56us
[187.102596] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.617554
last_received_ts = 188.616806
last_seq = 143
current_ts = 188.634232
current_received_ts = 188.633560
current_seq = 144
count = 81
seq_step = 1
[187.102650] (kms_flip:3984) DEBUG: Vblank took 50us
[187.119137] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.634232
last_received_ts = 188.633560
last_seq = 144
current_ts = 188.650894
current_received_ts = 188.650101
current_seq = 145
count = 82
seq_step = 1
[187.119187] (kms_flip:3984) DEBUG: Vblank took 47us
[187.135912] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.650894
last_received_ts = 188.650101
last_seq = 145
current_ts = 188.667557
current_received_ts = 188.666885
current_seq = 146
count = 83
seq_step = 1
[187.135957] (kms_flip:3984) DEBUG: Vblank took 43us
[187.152496] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.667557
last_received_ts = 188.666885
last_seq = 146
current_ts = 188.684219
current_received_ts = 188.683456
current_seq = 147
count = 84
seq_step = 1
[187.152553] (kms_flip:3984) DEBUG: Vblank took 54us
[187.169252] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.684219
last_received_ts = 188.683456
last_seq = 147
current_ts = 188.700882
current_received_ts = 188.700211
current_seq = 148
count = 85
seq_step = 1
[187.169308] (kms_flip:3984) DEBUG: Vblank took 54us
[187.185955] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.700882
last_received_ts = 188.700211
last_seq = 148
current_ts = 188.717545
current_received_ts = 188.716919
current_seq = 149
count = 86
seq_step = 1
[187.186020] (kms_flip:3984) DEBUG: Vblank took 62us
[187.202584] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.717545
last_received_ts = 188.716919
last_seq = 149
current_ts = 188.734222
current_received_ts = 188.733551
current_seq = 150
count = 87
seq_step = 1
[187.202635] (kms_flip:3984) DEBUG: Vblank took 48us
[187.219266] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.734222
last_received_ts = 188.733551
last_seq = 150
current_ts = 188.750885
current_received_ts = 188.750229
current_seq = 151
count = 88
seq_step = 1
[187.219305] (kms_flip:3984) DEBUG: Vblank took 37us
[187.235901] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.750885
last_received_ts = 188.750229
last_seq = 151
current_ts = 188.767548
current_received_ts = 188.766876
current_seq = 152
count = 89
seq_step = 1
[187.235949] (kms_flip:3984) DEBUG: Vblank took 46us
[187.252610] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.767548
last_received_ts = 188.766876
last_seq = 152
current_ts = 188.784210
current_received_ts = 188.783585
current_seq = 153
count = 90
seq_step = 1
[187.252672] (kms_flip:3984) DEBUG: Vblank took 59us
[187.269124] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.784210
last_received_ts = 188.783585
last_seq = 153
current_ts = 188.800873
current_received_ts = 188.800095
current_seq = 154
count = 91
seq_step = 1
[187.269185] (kms_flip:3984) DEBUG: Vblank took 56us
[187.285946] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.800873
last_received_ts = 188.800095
last_seq = 154
current_ts = 188.817535
current_received_ts = 188.816925
current_seq = 155
count = 92
seq_step = 1
[187.286012] (kms_flip:3984) DEBUG: Vblank took 62us
[187.302469] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.817535
last_received_ts = 188.816925
last_seq = 155
current_ts = 188.834213
current_received_ts = 188.833450
current_seq = 156
count = 93
seq_step = 1
[187.302521] (kms_flip:3984) DEBUG: Vblank took 49us
[187.319216] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.834213
last_received_ts = 188.833450
last_seq = 156
current_ts = 188.850876
current_received_ts = 188.850189
current_seq = 157
count = 94
seq_step = 1
[187.319254] (kms_flip:3984) DEBUG: Vblank took 35us
[187.335887] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.850876
last_received_ts = 188.850189
last_seq = 157
current_ts = 188.867538
current_received_ts = 188.866867
current_seq = 158
count = 95
seq_step = 1
[187.335939] (kms_flip:3984) DEBUG: Vblank took 49us
[187.352543] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.867538
last_received_ts = 188.866867
last_seq = 158
current_ts = 188.884201
current_received_ts = 188.883514
current_seq = 159
count = 96
seq_step = 1
[187.352666] (kms_flip:3984) DEBUG: Vblank took 120us
[187.369173] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.884201
last_received_ts = 188.883514
last_seq = 159
current_ts = 188.900864
current_received_ts = 188.900146
current_seq = 160
count = 97
seq_step = 1
[187.369221] (kms_flip:3984) DEBUG: Vblank took 46us
[187.385762] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.900864
last_received_ts = 188.900146
last_seq = 160
current_ts = 188.917526
current_received_ts = 188.916748
current_seq = 161
count = 98
seq_step = 1
[187.385815] (kms_flip:3984) DEBUG: Vblank took 50us
[187.402433] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.917526
last_received_ts = 188.916748
last_seq = 161
current_ts = 188.934204
current_received_ts = 188.933411
current_seq = 162
count = 99
seq_step = 1
[187.402483] (kms_flip:3984) DEBUG: Vblank took 47us
[187.419205] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.934204
last_received_ts = 188.933411
last_seq = 162
current_ts = 188.950867
current_received_ts = 188.950180
current_seq = 163
count = 100
seq_step = 1
[187.419276] (kms_flip:3984) DEBUG: Vblank took 66us
[187.435853] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.950867
last_received_ts = 188.950180
last_seq = 163
current_ts = 188.967529
current_received_ts = 188.966843
current_seq = 164
count = 101
seq_step = 1
[187.435904] (kms_flip:3984) DEBUG: Vblank took 49us
[187.452411] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.967529
last_received_ts = 188.966843
last_seq = 164
current_ts = 188.984192
current_received_ts = 188.983398
current_seq = 165
count = 102
seq_step = 1
[187.452469] (kms_flip:3984) DEBUG: Vblank took 55us
[187.469189] (kms_flip:3984) DEBUG: name = flip
last_ts = 188.984192
last_received_ts = 188.983398
last_seq = 165
current_ts = 189.000854
current_received_ts = 189.000168
current_seq = 166
count = 103
seq_step = 1
[187.469250] (kms_flip:3984) DEBUG: Vblank took 57us
[187.485858] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.000854
last_received_ts = 189.000168
last_seq = 166
current_ts = 189.017517
current_received_ts = 189.016846
current_seq = 167
count = 104
seq_step = 1
[187.485916] (kms_flip:3984) DEBUG: Vblank took 56us
[187.502525] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.017517
last_received_ts = 189.016846
last_seq = 167
current_ts = 189.034195
current_received_ts = 189.033508
current_seq = 168
count = 105
seq_step = 1
[187.502586] (kms_flip:3984) DEBUG: Vblank took 59us
[187.519074] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.034195
last_received_ts = 189.033508
last_seq = 168
current_ts = 189.050858
current_received_ts = 189.050064
current_seq = 169
count = 106
seq_step = 1
[187.519134] (kms_flip:3984) DEBUG: Vblank took 57us
[187.535847] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.050858
last_received_ts = 189.050064
last_seq = 169
current_ts = 189.067520
current_received_ts = 189.066833
current_seq = 170
count = 107
seq_step = 1
[187.535913] (kms_flip:3984) DEBUG: Vblank took 63us
[187.552424] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.067520
last_received_ts = 189.066833
last_seq = 170
current_ts = 189.084183
current_received_ts = 189.083420
current_seq = 171
count = 108
seq_step = 1
[187.552479] (kms_flip:3984) DEBUG: Vblank took 51us
[187.569177] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.084183
last_received_ts = 189.083420
last_seq = 171
current_ts = 189.100845
current_received_ts = 189.100174
current_seq = 172
count = 109
seq_step = 1
[187.569230] (kms_flip:3984) DEBUG: Vblank took 50us
[187.585829] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.100845
last_received_ts = 189.100174
last_seq = 172
current_ts = 189.117523
current_received_ts = 189.116821
current_seq = 173
count = 110
seq_step = 1
[187.585876] (kms_flip:3984) DEBUG: Vblank took 46us
[187.602500] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.117523
last_received_ts = 189.116821
last_seq = 173
current_ts = 189.134186
current_received_ts = 189.133499
current_seq = 174
count = 111
seq_step = 1
[187.602552] (kms_flip:3984) DEBUG: Vblank took 50us
[187.619183] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.134186
last_received_ts = 189.133499
last_seq = 174
current_ts = 189.150848
current_received_ts = 189.150177
current_seq = 175
count = 112
seq_step = 1
[187.619244] (kms_flip:3984) DEBUG: Vblank took 57us
[187.635753] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.150848
last_received_ts = 189.150177
last_seq = 175
current_ts = 189.167511
current_received_ts = 189.166748
current_seq = 176
count = 113
seq_step = 1
[187.635806] (kms_flip:3984) DEBUG: Vblank took 51us
[187.652506] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.167511
last_received_ts = 189.166748
last_seq = 176
current_ts = 189.184174
current_received_ts = 189.183502
current_seq = 177
count = 114
seq_step = 1
[187.652557] (kms_flip:3984) DEBUG: Vblank took 49us
[187.669187] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.184174
last_received_ts = 189.183502
last_seq = 177
current_ts = 189.200836
current_received_ts = 189.200180
current_seq = 178
count = 115
seq_step = 1
[187.669246] (kms_flip:3984) DEBUG: Vblank took 55us
[187.685832] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.200836
last_received_ts = 189.200180
last_seq = 178
current_ts = 189.217514
current_received_ts = 189.216827
current_seq = 179
count = 116
seq_step = 1
[187.685887] (kms_flip:3984) DEBUG: Vblank took 52us
[187.702501] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.217514
last_received_ts = 189.216827
last_seq = 179
current_ts = 189.234177
current_received_ts = 189.233505
current_seq = 180
count = 117
seq_step = 1
[187.702556] (kms_flip:3984) DEBUG: Vblank took 52us
[187.719060] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.234177
last_received_ts = 189.233505
last_seq = 180
current_ts = 189.250839
current_received_ts = 189.250061
current_seq = 181
count = 118
seq_step = 1
[187.719117] (kms_flip:3984) DEBUG: Vblank took 54us
[187.735728] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.250839
last_received_ts = 189.250061
last_seq = 181
current_ts = 189.267502
current_received_ts = 189.266724
current_seq = 182
count = 119
seq_step = 1
[187.735778] (kms_flip:3984) DEBUG: Vblank took 46us
[187.752509] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.267502
last_received_ts = 189.266724
last_seq = 182
current_ts = 189.284164
current_received_ts = 189.283508
current_seq = 183
count = 120
seq_step = 1
[187.752568] (kms_flip:3984) DEBUG: Vblank took 54us
[187.769151] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.284164
last_received_ts = 189.283508
last_seq = 183
current_ts = 189.300827
current_received_ts = 189.300156
current_seq = 184
count = 121
seq_step = 1
[187.769213] (kms_flip:3984) DEBUG: Vblank took 58us
[187.785827] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.300827
last_received_ts = 189.300156
last_seq = 184
current_ts = 189.317490
current_received_ts = 189.316833
current_seq = 185
count = 122
seq_step = 1
[187.785889] (kms_flip:3984) DEBUG: Vblank took 58us
[187.802389] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.317490
last_received_ts = 189.316833
last_seq = 185
current_ts = 189.334152
current_received_ts = 189.333389
current_seq = 186
count = 123
seq_step = 1
[187.802451] (kms_flip:3984) DEBUG: Vblank took 59us
[187.819144] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.334152
last_received_ts = 189.333389
last_seq = 186
current_ts = 189.350830
current_received_ts = 189.350159
current_seq = 187
count = 124
seq_step = 1
[187.819205] (kms_flip:3984) DEBUG: Vblank took 58us
[187.835807] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.350830
last_received_ts = 189.350159
last_seq = 187
current_ts = 189.367493
current_received_ts = 189.366821
current_seq = 188
count = 125
seq_step = 1
[187.835867] (kms_flip:3984) DEBUG: Vblank took 56us
[187.852379] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.367493
last_received_ts = 189.366821
last_seq = 188
current_ts = 189.384155
current_received_ts = 189.383392
current_seq = 189
count = 126
seq_step = 1
[187.852440] (kms_flip:3984) DEBUG: Vblank took 58us
[187.869087] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.384155
last_received_ts = 189.383392
last_seq = 189
current_ts = 189.400818
current_received_ts = 189.400101
current_seq = 190
count = 127
seq_step = 1
[187.869134] (kms_flip:3984) DEBUG: Vblank took 45us
[187.885711] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.400818
last_received_ts = 189.400101
last_seq = 190
current_ts = 189.417480
current_received_ts = 189.416718
current_seq = 191
count = 128
seq_step = 1
[187.885764] (kms_flip:3984) DEBUG: Vblank took 49us
[187.902467] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.417480
last_received_ts = 189.416718
last_seq = 191
current_ts = 189.434143
current_received_ts = 189.433487
current_seq = 192
count = 129
seq_step = 1
[187.902518] (kms_flip:3984) DEBUG: Vblank took 48us
[187.919113] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.434143
last_received_ts = 189.433487
last_seq = 192
current_ts = 189.450821
current_received_ts = 189.450134
current_seq = 193
count = 130
seq_step = 1
[187.919155] (kms_flip:3984) DEBUG: Vblank took 40us
[187.935689] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.450821
last_received_ts = 189.450134
last_seq = 193
current_ts = 189.467484
current_received_ts = 189.466705
current_seq = 194
count = 131
seq_step = 1
[187.935742] (kms_flip:3984) DEBUG: Vblank took 50us
[187.952454] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.467484
last_received_ts = 189.466705
last_seq = 194
current_ts = 189.484146
current_received_ts = 189.483475
current_seq = 195
count = 132
seq_step = 1
[187.952500] (kms_flip:3984) DEBUG: Vblank took 43us
[187.969063] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.484146
last_received_ts = 189.483475
last_seq = 195
current_ts = 189.500809
current_received_ts = 189.500076
current_seq = 196
count = 133
seq_step = 1
[187.969109] (kms_flip:3984) DEBUG: Vblank took 44us
[187.985657] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.500809
last_received_ts = 189.500076
last_seq = 196
current_ts = 189.517471
current_received_ts = 189.516678
current_seq = 197
count = 134
seq_step = 1
[187.985707] (kms_flip:3984) DEBUG: Vblank took 48us
[188.002324] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.517471
last_received_ts = 189.516678
last_seq = 197
current_ts = 189.534149
current_received_ts = 189.533340
current_seq = 198
count = 135
seq_step = 1
[188.002387] (kms_flip:3984) DEBUG: Vblank took 57us
[188.019001] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.534149
last_received_ts = 189.533340
last_seq = 198
current_ts = 189.550812
current_received_ts = 189.550018
current_seq = 199
count = 136
seq_step = 1
[188.019057] (kms_flip:3984) DEBUG: Vblank took 53us
[188.035682] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.550812
last_received_ts = 189.550018
last_seq = 199
current_ts = 189.567474
current_received_ts = 189.566711
current_seq = 200
count = 137
seq_step = 1
[188.035735] (kms_flip:3984) DEBUG: Vblank took 49us
[188.052412] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.567474
last_received_ts = 189.566711
last_seq = 200
current_ts = 189.584137
current_received_ts = 189.583435
current_seq = 201
count = 138
seq_step = 1
[188.052462] (kms_flip:3984) DEBUG: Vblank took 47us
[188.069100] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.584137
last_received_ts = 189.583435
last_seq = 201
current_ts = 189.600800
current_received_ts = 189.600128
current_seq = 202
count = 139
seq_step = 1
[188.069149] (kms_flip:3984) DEBUG: Vblank took 46us
[188.085772] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.600800
last_received_ts = 189.600128
last_seq = 202
current_ts = 189.617462
current_received_ts = 189.616791
current_seq = 203
count = 140
seq_step = 1
[188.085848] (kms_flip:3984) DEBUG: Vblank took 72us
[188.102363] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.617462
last_received_ts = 189.616791
last_seq = 203
current_ts = 189.634125
current_received_ts = 189.633392
current_seq = 204
count = 141
seq_step = 1
[188.102426] (kms_flip:3984) DEBUG: Vblank took 59us
[188.119095] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.634125
last_received_ts = 189.633392
last_seq = 204
current_ts = 189.650787
current_received_ts = 189.650116
current_seq = 205
count = 142
seq_step = 1
[188.119137] (kms_flip:3984) DEBUG: Vblank took 38us
[188.135777] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.650787
last_received_ts = 189.650116
last_seq = 205
current_ts = 189.667465
current_received_ts = 189.666809
current_seq = 206
count = 143
seq_step = 1
[188.135839] (kms_flip:3984) DEBUG: Vblank took 59us
[188.152439] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.667465
last_received_ts = 189.666809
last_seq = 206
current_ts = 189.684128
current_received_ts = 189.683472
current_seq = 207
count = 144
seq_step = 1
[188.152495] (kms_flip:3984) DEBUG: Vblank took 53us
[188.169070] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.684128
last_received_ts = 189.683472
last_seq = 207
current_ts = 189.700790
current_received_ts = 189.700104
current_seq = 208
count = 145
seq_step = 1
[188.169121] (kms_flip:3984) DEBUG: Vblank took 49us
[188.185641] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.700790
last_received_ts = 189.700104
last_seq = 208
current_ts = 189.717453
current_received_ts = 189.716675
current_seq = 209
count = 146
seq_step = 1
[188.185692] (kms_flip:3984) DEBUG: Vblank took 49us
[188.202411] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.717453
last_received_ts = 189.716675
last_seq = 209
current_ts = 189.734116
current_received_ts = 189.733444
current_seq = 210
count = 147
seq_step = 1
[188.202457] (kms_flip:3984) DEBUG: Vblank took 44us
[188.219086] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.734116
last_received_ts = 189.733444
last_seq = 210
current_ts = 189.750793
current_received_ts = 189.750122
current_seq = 211
count = 148
seq_step = 1
[188.219138] (kms_flip:3984) DEBUG: Vblank took 50us
[188.235744] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.750793
last_received_ts = 189.750122
last_seq = 211
current_ts = 189.767456
current_received_ts = 189.766785
current_seq = 212
count = 149
seq_step = 1
[188.235789] (kms_flip:3984) DEBUG: Vblank took 43us
[188.252437] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.767456
last_received_ts = 189.766785
last_seq = 212
current_ts = 189.784119
current_received_ts = 189.783417
current_seq = 213
count = 150
seq_step = 1
[188.252690] (kms_flip:3984) DEBUG: Vblank took 212us
[188.269043] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.784119
last_received_ts = 189.783417
last_seq = 213
current_ts = 189.800781
current_received_ts = 189.800064
current_seq = 214
count = 151
seq_step = 1
[188.269199] (kms_flip:3984) DEBUG: Vblank took 137us
[188.285664] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.800781
last_received_ts = 189.800064
last_seq = 214
current_ts = 189.817444
current_received_ts = 189.816681
current_seq = 215
count = 152
seq_step = 1
[188.285801] (kms_flip:3984) DEBUG: Vblank took 122us
[188.302434] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.817444
last_received_ts = 189.816681
last_seq = 215
current_ts = 189.834106
current_received_ts = 189.833466
current_seq = 216
count = 153
seq_step = 1
[188.302564] (kms_flip:3984) DEBUG: Vblank took 115us
[188.321355] (kms_flip:3984) DEBUG: name = flip
last_ts = 189.834106
last_received_ts = 189.833466
last_seq = 216
current_ts = 189.850784
current_received_ts = 189.852386
current_seq = 217
count = 154
seq_step = 1
[188.322099] (kms_flip:3984) DEBUG: Vblank took 731us
[188.322108] (kms_flip:3984) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[188.322292] (kms_flip:3984) CRITICAL: Failed assertion: end - start < 500
[188.322373] (kms_flip:3984) CRITICAL: Last errno: 4, Interrupted system call
[188.327496] (kms_flip:3984) igt_core-INFO: Stack trace:
[188.332010] (kms_flip:3984) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[188.333541] (kms_flip:3984) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[188.333609] (kms_flip:3984) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[188.333657] (kms_flip:3984) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[188.333701] (kms_flip:3984) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[188.379159] (kms_flip:3984) igt_core-INFO:   #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
[188.379306] (kms_flip:3984) igt_core-INFO:   #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
[188.379850] (kms_flip:3984) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (3.871s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (8.065s)
[189.577261] (kms_flip:3984) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2354:
[189.577356] (kms_flip:3984) igt_kms-CRITICAL: Failed assertion: found_it
[189.577406] (kms_flip:3984) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[189.577438] (kms_flip:3984) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [200.932157] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [200.934132] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:435]
<7> [200.938260] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:436]
<7> [200.992747] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [200.992887] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [200.993802] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [200.994082] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [200.994158] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [200.994305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [200.994468] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [200.994600] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [200.994728] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270: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> [200.994862] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [200.994982] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [200.995099] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [200.995216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [200.995335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [200.995448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [200.995557] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [200.995666] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [200.995774] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [200.995879] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [200.995983] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [200.996081] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [200.996182] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [200.996282] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [200.996392] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [200.996491] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [200.996591] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [200.996690] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [200.996789] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [200.996896] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [200.996999] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [200.997100] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [200.997200] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [200.997297] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [200.997406] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [200.997503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [200.997602] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [200.997698] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [200.997795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [200.997888] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [200.997969] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [200.998067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [200.998162] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [200.998258] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [200.998364] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [200.998500] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [200.998591] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [200.998707] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:154:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [200.998797] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [200.998863] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [200.998924] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [200.998993] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [200.999065] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] 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> [200.999157] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:270:pipe B] data rate 0 num active planes 0
<7> [200.999288] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 34060 required 0 qgv_peak_bw: 38400
<7> [200.999416] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [200.999525] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [200.999633] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [200.999737] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [200.999841] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [200.999956] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [201.000059] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [201.000160] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [201.000264] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [201.000483] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [201.000590] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [201.000695] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [201.000798] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [201.000888] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [201.000975] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [201.001082] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [201.001251] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [201.001992] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [201.002110] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [201.206489] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [201.207156] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [201.219796] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [201.220567] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [201.221138] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [201.272665] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [201.272955] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [201.273380] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [201.273458] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [201.274056] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [201.275182] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [201.275869] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [201.276586] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [201.277068] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [201.277523] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [201.277949] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [201.278396] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [201.278783] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [201.279141] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [201.279522] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [201.279878] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [201.280234] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [201.280604] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [201.280971] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [201.281348] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [201.281714] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [201.282163] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [201.282528] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [201.282863] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [201.283477] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [201.283959] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [201.284496] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [201.284996] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [201.285393] 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> [201.285882] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [201.286214] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [201.287530] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [201.287982] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [201.288081] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [201.288371] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [201.288869] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:389:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [201.289226] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [201.289682] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [201.289963] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [201.290260] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [201.290573] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [201.290851] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [201.291106] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [201.291359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [201.291601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389: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> [201.291836] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [201.292057] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [201.292266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [201.292481] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [201.292681] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [201.292873] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [201.293059] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [201.293237] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [201.293430] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [201.293602] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [201.293770] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [201.293931] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [201.294091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [201.294244] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [201.294403] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [201.294553] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [201.294699] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [201.294845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [201.294983] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [201.295116] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [201.295247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [201.295380] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [201.295505] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [201.295629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [201.295750] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [201.295867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [201.295981] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [201.296094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [201.296205] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [201.296323] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [201.296436] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [201.296540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [201.296643] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [201.296751] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [201.296899] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [201.296988] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [201.297115] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [201.297201] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [201.297277] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [201.297369] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [201.297453] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [201.297527] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [201.297630] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [201.297757] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23529 required 1461 qgv_peak_bw: 38400
<7> [201.297876] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36259 required 1461 qgv_peak_bw: 38400
<7> [201.297991] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [201.298105] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [201.298219] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [201.298367] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [201.298518] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [201.298636] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [201.298751] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [201.298864] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [201.299008] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [201.299117] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [201.299227] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [201.299354] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [201.299478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [201.299591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [201.299704] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [201.299817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [201.299929] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [201.300040] 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> [201.300151] 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> [201.300263] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [201.300380] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [201.300492] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [201.300604] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [201.300715] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [201.300827] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [201.300939] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [201.301050] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [201.301160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [201.301270] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [201.301388] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [201.301503] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [201.301614] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [201.301726] 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: 37 vsync start: 103, vsync end: 97
<7> [201.301838] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1869, vmax vblank: 1869, vmin vtotal: 1906, vmax vtotal: 1906
<7> [201.301948] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [201.302059] 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> [201.302171] 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> [201.302282] 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> [201.302401] 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> [201.302514] 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> [201.302626] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [201.302737] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [201.302847] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [201.302957] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [201.303068] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [201.303178] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [201.303289] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [201.303406] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [201.303519] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [201.303629] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [201.303739] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [201.303849] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [201.303958] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [201.304069] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [201.304180] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [201.304291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [201.304410] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [201.304524] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [201.304634] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [201.304744] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [201.304855] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.304965] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.305076] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.305187] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [201.305298] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [201.305425] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.305438] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [201.305544] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.305613] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [201.305656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [201.305737] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [201.305765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [201.305875] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [201.305876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [FB:435] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [201.305988] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [201.306100] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [201.306211] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [201.306327] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [201.306441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [201.306551] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [201.306660] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [201.307083] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [201.307217] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [201.307338] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [201.307516] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [201.307676] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [201.307846] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [201.307975] 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> [201.308107] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [201.308223] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [201.308358] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [201.308464] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [201.308553] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [201.308640] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [201.308725] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [201.308809] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [201.308893] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [201.308974] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [201.309056] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [201.309137] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [201.309217] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [201.309297] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [201.309385] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [201.309495] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [201.309690] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [201.309825] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [201.310641] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [201.310789] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [201.310892] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [201.311048] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [201.311202] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [201.311376] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [201.314602] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [201.314715] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (459 ms remaining)
<7> [201.782478] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [201.853900] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [201.854415] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [201.854844] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [201.890558] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [201.891050] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [201.891524] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [201.892399] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [202.068118] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [202.068689] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [202.069086] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [202.071320] 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> [202.072863] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [202.073685] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [202.074870] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [202.076240] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [202.077429] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [202.077834] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [202.079365] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [202.079759] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [202.080563] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [202.081038] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [202.081454] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [202.081881] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [202.101063] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 29491200 bytes of contiguous stolen space for FBC, limit: 1
<7> [202.101629] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [202.114482] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [202.116120] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [202.116656] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [204.640522] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [204.641059] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [204.806289] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [204.809737] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [204.815538] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:435] from all active usage due to RMFB ioctl
<7> [204.816786] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:435] is removed
<7> [204.816996] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [204.817289] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [204.817444] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [204.817584] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [204.818914] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [204.819160] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [204.819350] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 34060 required 0 qgv_peak_bw: 38400
<7> [204.819523] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [204.819687] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [204.819845] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [204.819997] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [204.821883] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [204.822320] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [204.822465] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [204.830668] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [204.865024] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [204.865207] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [204.865332] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [204.865457] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [204.865588] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [204.865709] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [204.865823] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [204.865935] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [204.866046] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [204.866167] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151: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> [204.866280] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [204.866391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [204.866501] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [204.866611] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [204.866720] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [204.866829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [204.866938] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [204.867048] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [204.867189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [204.867297] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [204.867404] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [204.867511] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [204.867617] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [204.867724] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [204.867830] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [204.867936] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [204.868042] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [204.868172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [204.868281] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [204.868388] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [204.868494] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [204.868600] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [204.868705] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [204.868812] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [204.868917] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [204.869023] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [204.869135] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [204.869241] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [204.869347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [204.869455] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [204.869562] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [204.869668] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [204.869775] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [204.869885] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [204.869991] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [204.870105] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [204.870213] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [204.870320] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [204.870427] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389: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> [204.870535] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [204.870641] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [204.870746] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [204.870852] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [204.870959] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [204.871064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [204.871270] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [204.871380] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [204.871486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [204.871593] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [204.871697] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [204.871803] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [204.871908] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [204.872015] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [204.872334] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [204.872443] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [204.872549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [204.872655] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [204.872760] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [204.872865] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [204.872970] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [204.873080] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [204.873187] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [204.873292] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [204.873397] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [204.873501] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [204.873605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [204.873711] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [204.873816] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [204.873920] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [204.874025] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [204.874136] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [204.874244] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [204.874350] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [204.874457] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [204.874672] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [204.874768] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [204.874850] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [204.874932] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [204.875007] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [204.875090] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [204.875165] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [204.875235] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [204.875306] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [204.875377] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [204.875477] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [204.875604] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23529 required 1461 qgv_peak_bw: 38400
<7> [204.875722] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36259 required 1461 qgv_peak_bw: 38400
<7> [204.875839] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [204.875956] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [204.876192] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [204.876318] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [204.876437] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [204.876626] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [204.876737] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [204.876851] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [204.876973] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [204.877100] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [204.877224] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [204.877341] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [204.877454] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [204.877567] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [204.877681] 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> [204.877796] 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> [204.877910] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [204.878023] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [204.878143] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [204.878258] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [204.878372] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [204.878485] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [204.878598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [204.878709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [204.878821] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [204.878932] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [204.879043] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [204.879164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [204.879276] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [204.879388] 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: 37 vsync start: 103, vsync end: 97
<7> [204.879501] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1869, vmax vblank: 1869, vmin vtotal: 1906, vmax vtotal: 1906
<7> [204.879611] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [204.879722] 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> [204.879833] 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> [204.879945] 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> [204.880057] 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> [204.880198] 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> [204.880313] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [204.880423] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [204.880535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [204.880645] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [204.880755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [204.880866] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [204.880975] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [204.881095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [204.881209] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [204.881319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [204.881429] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [204.881539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [204.881650] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [204.881760] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [204.881870] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [204.881980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [204.882097] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [204.882213] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [204.882329] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [204.882449] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [204.882558] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.882668] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.882777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.882886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [204.882995] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [204.883112] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.883228] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.883337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [204.883447] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [204.883556] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:394] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [204.883669] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [204.883779] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [204.883890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [204.884000] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [204.884148] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [204.884265] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [204.884379] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [204.884489] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [204.884600] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [204.884710] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [204.884819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [204.884929] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [204.885040] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [204.885161] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [204.885456] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [204.886961] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [204.887362] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [204.887526] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [205.094187] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [205.098262] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [205.114553] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [205.114896] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [205.115253] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [205.115525] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [205.115796] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [205.167622] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [205.168066] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [205.168683] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [205.169863] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [205.169911] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [205.170791] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [205.171839] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [205.172597] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [205.173404] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [205.173938] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [205.174490] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [205.174985] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [205.175504] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [205.175994] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [205.176513] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [205.177002] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [205.177518] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [205.178001] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [205.178518] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [205.179004] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [205.179505] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [205.180545] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [205.181197] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [205.181542] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [205.181934] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [205.183093] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [205.183452] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [205.185890] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [205.186191] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (484 ms remaining)
<7> [205.205157] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [205.205710] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [205.206253] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [205.206831] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [205.678440] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [205.689838] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [205.691007] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [205.691962] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [205.727075] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [205.727585] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [205.728119] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [205.728855] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [205.903654] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [205.904928] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [205.905972] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [205.909224] 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> [205.911523] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [205.913183] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [205.915228] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [205.917573] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [205.919128] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [205.919703] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [205.921510] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [205.922111] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [205.923200] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [205.923917] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [205.924427] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [205.924982] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [205.957648] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [205.960210] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [205.960927] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [205.964672] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [205.965396] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [205.966246] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [205.966968] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [205.967821] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [205.971593] [IGT] kms_flip: exiting, ret=98
<7> [205.986067] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [205.986328] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [205.986455] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-06-05 21:08:48