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 boot13 dmesg13

DetailValue
Duration 7.48 seconds
Hostname 
shard-lnl-8
Igt-Version 
IGT-Version: 2.3-g1072e4f2e (x86_64) (Linux: 6.19.0-rc1-lgci-xe-xe-4261-c5b056356f247370c+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (7.484s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (23.671s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2386 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
(kms_flip:5486) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:5486) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5486) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:5486) DEBUG: Vblank took 41us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.496735
last_received_ts = 372.495941
last_seq = 1035
current_ts = 372.513397
current_received_ts = 372.512634
current_seq = 1036
count = 251
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 37us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.513397
last_received_ts = 372.512634
last_seq = 1036
current_ts = 372.530090
current_received_ts = 372.529266
current_seq = 1037
count = 252
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 40us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.530090
last_received_ts = 372.529266
last_seq = 1037
current_ts = 372.546753
current_received_ts = 372.545959
current_seq = 1038
count = 253
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 53us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.546753
last_received_ts = 372.545959
last_seq = 1038
current_ts = 372.563416
current_received_ts = 372.562653
current_seq = 1039
count = 254
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 96us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.563416
last_received_ts = 372.562653
last_seq = 1039
current_ts = 372.580078
current_received_ts = 372.579285
current_seq = 1040
count = 255
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.580078
last_received_ts = 372.579285
last_seq = 1040
current_ts = 372.596771
current_received_ts = 372.595978
current_seq = 1041
count = 256
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 54us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.596771
last_received_ts = 372.595978
last_seq = 1041
current_ts = 372.613434
current_received_ts = 372.612671
current_seq = 1042
count = 257
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 96us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.613434
last_received_ts = 372.612671
last_seq = 1042
current_ts = 372.630096
current_received_ts = 372.629303
current_seq = 1043
count = 258
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.630096
last_received_ts = 372.629303
last_seq = 1043
current_ts = 372.646759
current_received_ts = 372.645996
current_seq = 1044
count = 259
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 68us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.646759
last_received_ts = 372.645996
last_seq = 1044
current_ts = 372.663422
current_received_ts = 372.662659
current_seq = 1045
count = 260
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 55us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.663422
last_received_ts = 372.662659
last_seq = 1045
current_ts = 372.680115
current_received_ts = 372.679321
current_seq = 1046
count = 261
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 54us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.680115
last_received_ts = 372.679321
last_seq = 1046
current_ts = 372.696777
current_received_ts = 372.695984
current_seq = 1047
count = 262
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 63us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.696777
last_received_ts = 372.695984
last_seq = 1047
current_ts = 372.713440
current_received_ts = 372.712677
current_seq = 1048
count = 263
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 59us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.713440
last_received_ts = 372.712677
last_seq = 1048
current_ts = 372.730103
current_received_ts = 372.729340
current_seq = 1049
count = 264
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 56us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.730103
last_received_ts = 372.729340
last_seq = 1049
current_ts = 372.746796
current_received_ts = 372.746033
current_seq = 1050
count = 265
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 55us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.746796
last_received_ts = 372.746033
last_seq = 1050
current_ts = 372.763458
current_received_ts = 372.762665
current_seq = 1051
count = 266
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.763458
last_received_ts = 372.762665
last_seq = 1051
current_ts = 372.780121
current_received_ts = 372.779327
current_seq = 1052
count = 267
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 47us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.780121
last_received_ts = 372.779327
last_seq = 1052
current_ts = 372.796783
current_received_ts = 372.795990
current_seq = 1053
count = 268
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 67us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.796783
last_received_ts = 372.795990
last_seq = 1053
current_ts = 372.813446
current_received_ts = 372.812653
current_seq = 1054
count = 269
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 58us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.813446
last_received_ts = 372.812653
last_seq = 1054
current_ts = 372.830139
current_received_ts = 372.829346
current_seq = 1055
count = 270
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 55us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.830139
last_received_ts = 372.829346
last_seq = 1055
current_ts = 372.846802
current_received_ts = 372.846039
current_seq = 1056
count = 271
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 56us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.846802
last_received_ts = 372.846039
last_seq = 1056
current_ts = 372.863464
current_received_ts = 372.862701
current_seq = 1057
count = 272
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 58us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.863464
last_received_ts = 372.862701
last_seq = 1057
current_ts = 372.880127
current_received_ts = 372.879364
current_seq = 1058
count = 273
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 50us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.880127
last_received_ts = 372.879364
last_seq = 1058
current_ts = 372.896820
current_received_ts = 372.896027
current_seq = 1059
count = 274
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.896820
last_received_ts = 372.896027
last_seq = 1059
current_ts = 372.913483
current_received_ts = 372.912689
current_seq = 1060
count = 275
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 53us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.913483
last_received_ts = 372.912689
last_seq = 1060
current_ts = 372.930145
current_received_ts = 372.929352
current_seq = 1061
count = 276
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 50us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.930145
last_received_ts = 372.929352
last_seq = 1061
current_ts = 372.946808
current_received_ts = 372.946045
current_seq = 1062
count = 277
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.946808
last_received_ts = 372.946045
last_seq = 1062
current_ts = 372.963501
current_received_ts = 372.962708
current_seq = 1063
count = 278
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 53us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.963501
last_received_ts = 372.962708
last_seq = 1063
current_ts = 372.980164
current_received_ts = 372.979370
current_seq = 1064
count = 279
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 59us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.980164
last_received_ts = 372.979370
last_seq = 1064
current_ts = 372.996826
current_received_ts = 372.996063
current_seq = 1065
count = 280
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 59us
(kms_flip:5486) DEBUG: name = flip
last_ts = 372.996826
last_received_ts = 372.996063
last_seq = 1065
current_ts = 373.013489
current_received_ts = 373.012726
current_seq = 1066
count = 281
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 39us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.013489
last_received_ts = 373.012726
last_seq = 1066
current_ts = 373.030182
current_received_ts = 373.029388
current_seq = 1067
count = 282
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.030182
last_received_ts = 373.029388
last_seq = 1067
current_ts = 373.046844
current_received_ts = 373.046051
current_seq = 1068
count = 283
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 40us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.046844
last_received_ts = 373.046051
last_seq = 1068
current_ts = 373.063507
current_received_ts = 373.062683
current_seq = 1069
count = 284
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.063507
last_received_ts = 373.062683
last_seq = 1069
current_ts = 373.080170
current_received_ts = 373.079376
current_seq = 1070
count = 285
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 40us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.080170
last_received_ts = 373.079376
last_seq = 1070
current_ts = 373.096832
current_received_ts = 373.096069
current_seq = 1071
count = 286
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 49us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.096832
last_received_ts = 373.096069
last_seq = 1071
current_ts = 373.113525
current_received_ts = 373.112732
current_seq = 1072
count = 287
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 53us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.113525
last_received_ts = 373.112732
last_seq = 1072
current_ts = 373.130188
current_received_ts = 373.129395
current_seq = 1073
count = 288
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.130188
last_received_ts = 373.129395
last_seq = 1073
current_ts = 373.146851
current_received_ts = 373.146057
current_seq = 1074
count = 289
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 47us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.146851
last_received_ts = 373.146057
last_seq = 1074
current_ts = 373.163513
current_received_ts = 373.162750
current_seq = 1075
count = 290
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.163513
last_received_ts = 373.162750
last_seq = 1075
current_ts = 373.180176
current_received_ts = 373.179413
current_seq = 1076
count = 291
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.180176
last_received_ts = 373.179413
last_seq = 1076
current_ts = 373.196869
current_received_ts = 373.196075
current_seq = 1077
count = 292
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 41us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.196869
last_received_ts = 373.196075
last_seq = 1077
current_ts = 373.213531
current_received_ts = 373.212769
current_seq = 1078
count = 293
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 50us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.213531
last_received_ts = 373.212769
last_seq = 1078
current_ts = 373.230194
current_received_ts = 373.229401
current_seq = 1079
count = 294
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.230194
last_received_ts = 373.229401
last_seq = 1079
current_ts = 373.246857
current_received_ts = 373.246063
current_seq = 1080
count = 295
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 42us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.246857
last_received_ts = 373.246063
last_seq = 1080
current_ts = 373.263550
current_received_ts = 373.262756
current_seq = 1081
count = 296
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.263550
last_received_ts = 373.262756
last_seq = 1081
current_ts = 373.280212
current_received_ts = 373.279388
current_seq = 1082
count = 297
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.280212
last_received_ts = 373.279388
last_seq = 1082
current_ts = 373.296875
current_received_ts = 373.296082
current_seq = 1083
count = 298
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 41us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.296875
last_received_ts = 373.296082
last_seq = 1083
current_ts = 373.313538
current_received_ts = 373.312775
current_seq = 1084
count = 299
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 92us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.313538
last_received_ts = 373.312775
last_seq = 1084
current_ts = 373.330231
current_received_ts = 373.329437
current_seq = 1085
count = 300
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 42us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.330231
last_received_ts = 373.329437
last_seq = 1085
current_ts = 373.346893
current_received_ts = 373.346069
current_seq = 1086
count = 301
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 41us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.346893
last_received_ts = 373.346069
last_seq = 1086
current_ts = 373.363556
current_received_ts = 373.362793
current_seq = 1087
count = 302
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 42us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.363556
last_received_ts = 373.362793
last_seq = 1087
current_ts = 373.380219
current_received_ts = 373.379425
current_seq = 1088
count = 303
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 42us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.380219
last_received_ts = 373.379425
last_seq = 1088
current_ts = 373.396881
current_received_ts = 373.396088
current_seq = 1089
count = 304
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.396881
last_received_ts = 373.396088
last_seq = 1089
current_ts = 373.413574
current_received_ts = 373.412781
current_seq = 1090
count = 305
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.413574
last_received_ts = 373.412781
last_seq = 1090
current_ts = 373.430237
current_received_ts = 373.429443
current_seq = 1091
count = 306
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.430237
last_received_ts = 373.429443
last_seq = 1091
current_ts = 373.446899
current_received_ts = 373.446106
current_seq = 1092
count = 307
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 47us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.446899
last_received_ts = 373.446106
last_seq = 1092
current_ts = 373.463562
current_received_ts = 373.462799
current_seq = 1093
count = 308
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 42us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.463562
last_received_ts = 373.462799
last_seq = 1093
current_ts = 373.480255
current_received_ts = 373.479431
current_seq = 1094
count = 309
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.480255
last_received_ts = 373.479431
last_seq = 1094
current_ts = 373.496918
current_received_ts = 373.496124
current_seq = 1095
count = 310
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.496918
last_received_ts = 373.496124
last_seq = 1095
current_ts = 373.513580
current_received_ts = 373.512787
current_seq = 1096
count = 311
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.513580
last_received_ts = 373.512787
last_seq = 1096
current_ts = 373.530243
current_received_ts = 373.529480
current_seq = 1097
count = 312
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 98us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.530243
last_received_ts = 373.529480
last_seq = 1097
current_ts = 373.546936
current_received_ts = 373.546112
current_seq = 1098
count = 313
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.546936
last_received_ts = 373.546112
last_seq = 1098
current_ts = 373.563599
current_received_ts = 373.562805
current_seq = 1099
count = 314
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.563599
last_received_ts = 373.562805
last_seq = 1099
current_ts = 373.580261
current_received_ts = 373.579498
current_seq = 1100
count = 315
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 52us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.580261
last_received_ts = 373.579498
last_seq = 1100
current_ts = 373.596924
current_received_ts = 373.596130
current_seq = 1101
count = 316
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 52us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.596924
last_received_ts = 373.596130
last_seq = 1101
current_ts = 373.613586
current_received_ts = 373.612823
current_seq = 1102
count = 317
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 52us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.613586
last_received_ts = 373.612823
last_seq = 1102
current_ts = 373.630280
current_received_ts = 373.629486
current_seq = 1103
count = 318
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 43us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.630280
last_received_ts = 373.629486
last_seq = 1103
current_ts = 373.646942
current_received_ts = 373.646149
current_seq = 1104
count = 319
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 47us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.646942
last_received_ts = 373.646149
last_seq = 1104
current_ts = 373.663605
current_received_ts = 373.662842
current_seq = 1105
count = 320
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 52us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.663605
last_received_ts = 373.662842
last_seq = 1105
current_ts = 373.680267
current_received_ts = 373.679504
current_seq = 1106
count = 321
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.680267
last_received_ts = 373.679504
last_seq = 1106
current_ts = 373.696960
current_received_ts = 373.696228
current_seq = 1107
count = 322
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 93us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.696960
last_received_ts = 373.696228
last_seq = 1107
current_ts = 373.713623
current_received_ts = 373.713074
current_seq = 1108
count = 323
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 135us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.713623
last_received_ts = 373.713074
last_seq = 1108
current_ts = 373.730286
current_received_ts = 373.729523
current_seq = 1109
count = 324
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 69us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.730286
last_received_ts = 373.729523
last_seq = 1109
current_ts = 373.746948
current_received_ts = 373.746185
current_seq = 1110
count = 325
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 66us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.746948
last_received_ts = 373.746185
last_seq = 1110
current_ts = 373.763611
current_received_ts = 373.762848
current_seq = 1111
count = 326
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 57us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.763611
last_received_ts = 373.762848
last_seq = 1111
current_ts = 373.780304
current_received_ts = 373.779541
current_seq = 1112
count = 327
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 56us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.780304
last_received_ts = 373.779541
last_seq = 1112
current_ts = 373.796967
current_received_ts = 373.796173
current_seq = 1113
count = 328
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 103us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.796967
last_received_ts = 373.796173
last_seq = 1113
current_ts = 373.813629
current_received_ts = 373.812866
current_seq = 1114
count = 329
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 54us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.813629
last_received_ts = 373.812866
last_seq = 1114
current_ts = 373.830292
current_received_ts = 373.829529
current_seq = 1115
count = 330
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 63us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.830292
last_received_ts = 373.829529
last_seq = 1115
current_ts = 373.846985
current_received_ts = 373.846191
current_seq = 1116
count = 331
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 62us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.846985
last_received_ts = 373.846191
last_seq = 1116
current_ts = 373.863647
current_received_ts = 373.862854
current_seq = 1117
count = 332
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 55us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.863647
last_received_ts = 373.862854
last_seq = 1117
current_ts = 373.880310
current_received_ts = 373.879517
current_seq = 1118
count = 333
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.880310
last_received_ts = 373.879517
last_seq = 1118
current_ts = 373.896973
current_received_ts = 373.896179
current_seq = 1119
count = 334
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.896973
last_received_ts = 373.896179
last_seq = 1119
current_ts = 373.913666
current_received_ts = 373.912842
current_seq = 1120
count = 335
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.913666
last_received_ts = 373.912842
last_seq = 1120
current_ts = 373.930328
current_received_ts = 373.929565
current_seq = 1121
count = 336
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 97us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.930328
last_received_ts = 373.929565
last_seq = 1121
current_ts = 373.946991
current_received_ts = 373.946167
current_seq = 1122
count = 337
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.946991
last_received_ts = 373.946167
last_seq = 1122
current_ts = 373.963654
current_received_ts = 373.962891
current_seq = 1123
count = 338
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.963654
last_received_ts = 373.962891
last_seq = 1123
current_ts = 373.980316
current_received_ts = 373.979553
current_seq = 1124
count = 339
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.980316
last_received_ts = 373.979553
last_seq = 1124
current_ts = 373.997009
current_received_ts = 373.996185
current_seq = 1125
count = 340
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 44us
(kms_flip:5486) DEBUG: name = flip
last_ts = 373.997009
last_received_ts = 373.996185
last_seq = 1125
current_ts = 374.013672
current_received_ts = 374.012878
current_seq = 1126
count = 341
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.013672
last_received_ts = 374.012878
last_seq = 1126
current_ts = 374.030334
current_received_ts = 374.029541
current_seq = 1127
count = 342
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 59us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.030334
last_received_ts = 374.029541
last_seq = 1127
current_ts = 374.046997
current_received_ts = 374.046204
current_seq = 1128
count = 343
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 47us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.046997
last_received_ts = 374.046204
last_seq = 1128
current_ts = 374.063690
current_received_ts = 374.062897
current_seq = 1129
count = 344
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.063690
last_received_ts = 374.062897
last_seq = 1129
current_ts = 374.080353
current_received_ts = 374.079559
current_seq = 1130
count = 345
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 58us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.080353
last_received_ts = 374.079559
last_seq = 1130
current_ts = 374.097015
current_received_ts = 374.096375
current_seq = 1131
count = 346
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 88us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.097015
last_received_ts = 374.096375
last_seq = 1131
current_ts = 374.113678
current_received_ts = 374.112915
current_seq = 1132
count = 347
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 54us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.113678
last_received_ts = 374.112915
last_seq = 1132
current_ts = 374.130371
current_received_ts = 374.129547
current_seq = 1133
count = 348
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 52us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.130371
last_received_ts = 374.129547
last_seq = 1133
current_ts = 374.147034
current_received_ts = 374.146210
current_seq = 1134
count = 349
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 67us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.147034
last_received_ts = 374.146210
last_seq = 1134
current_ts = 374.163696
current_received_ts = 374.162903
current_seq = 1135
count = 350
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.163696
last_received_ts = 374.162903
last_seq = 1135
current_ts = 374.180359
current_received_ts = 374.179596
current_seq = 1136
count = 351
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.180359
last_received_ts = 374.179596
last_seq = 1136
current_ts = 374.197021
current_received_ts = 374.196228
current_seq = 1137
count = 352
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 50us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.197021
last_received_ts = 374.196228
last_seq = 1137
current_ts = 374.213715
current_received_ts = 374.212952
current_seq = 1138
count = 353
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 112us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.213715
last_received_ts = 374.212952
last_seq = 1138
current_ts = 374.230377
current_received_ts = 374.229584
current_seq = 1139
count = 354
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 67us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.230377
last_received_ts = 374.229584
last_seq = 1139
current_ts = 374.247040
current_received_ts = 374.246246
current_seq = 1140
count = 355
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 56us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.247040
last_received_ts = 374.246246
last_seq = 1140
current_ts = 374.263702
current_received_ts = 374.262909
current_seq = 1141
count = 356
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 64us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.263702
last_received_ts = 374.262909
last_seq = 1141
current_ts = 374.280365
current_received_ts = 374.279602
current_seq = 1142
count = 357
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.280365
last_received_ts = 374.279602
last_seq = 1142
current_ts = 374.297058
current_received_ts = 374.296234
current_seq = 1143
count = 358
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 48us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.297058
last_received_ts = 374.296234
last_seq = 1143
current_ts = 374.313721
current_received_ts = 374.312927
current_seq = 1144
count = 359
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 45us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.313721
last_received_ts = 374.312927
last_seq = 1144
current_ts = 374.330383
current_received_ts = 374.329620
current_seq = 1145
count = 360
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 51us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.330383
last_received_ts = 374.329620
last_seq = 1145
current_ts = 374.347046
current_received_ts = 374.346252
current_seq = 1146
count = 361
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.347046
last_received_ts = 374.346252
last_seq = 1146
current_ts = 374.363739
current_received_ts = 374.362946
current_seq = 1147
count = 362
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 63us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.363739
last_received_ts = 374.362946
last_seq = 1147
current_ts = 374.380402
current_received_ts = 374.379608
current_seq = 1148
count = 363
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 50us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.380402
last_received_ts = 374.379608
last_seq = 1148
current_ts = 374.397064
current_received_ts = 374.396271
current_seq = 1149
count = 364
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 53us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.397064
last_received_ts = 374.396271
last_seq = 1149
current_ts = 374.413727
current_received_ts = 374.412964
current_seq = 1150
count = 365
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 59us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.413727
last_received_ts = 374.412964
last_seq = 1150
current_ts = 374.430389
current_received_ts = 374.429626
current_seq = 1151
count = 366
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 49us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.430389
last_received_ts = 374.429626
last_seq = 1151
current_ts = 374.447083
current_received_ts = 374.446289
current_seq = 1152
count = 367
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 55us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.447083
last_received_ts = 374.446289
last_seq = 1152
current_ts = 374.463745
current_received_ts = 374.462982
current_seq = 1153
count = 368
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 60us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.463745
last_received_ts = 374.462982
last_seq = 1153
current_ts = 374.480408
current_received_ts = 374.479645
current_seq = 1154
count = 369
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 46us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.480408
last_received_ts = 374.479645
last_seq = 1154
current_ts = 374.497101
current_received_ts = 374.496277
current_seq = 1155
count = 370
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 58us
(kms_flip:5486) DEBUG: name = flip
last_ts = 374.497101
last_received_ts = 374.496277
last_seq = 1155
current_ts = 374.513763
current_received_ts = 374.512970
current_seq = 1156
count = 371
seq_step = 1
(kms_flip:5486) DEBUG: Vblank took 751us
(kms_flip:5486) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:5486) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5486) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:5486) igt_core-INFO: Stack trace:
(kms_flip:5486) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:5486) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:5486) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:5486) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:5486) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:5486) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:5486) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:5486) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (7.484s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (23.671s)
(kms_flip:5486) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2386:
(kms_flip:5486) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:5486) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:5486) igt_kms-CRITICAL: DPMS property not found on 391
Dmesg
<6> [445.629931] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [445.647027] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:433]
<7> [445.649508] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:434]
<7> [445.698967] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [445.699067] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [445.699207] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [445.699416] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [445.699477] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [445.699592] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [445.699703] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [445.699807] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [445.699905] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268: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> [445.700001] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [445.700093] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [445.700183] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [445.700271] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [445.700359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [445.700444] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [445.700563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [445.700661] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [445.700759] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [445.700845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [445.700925] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [445.701010] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [445.701091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [445.701167] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [445.701245] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [445.701325] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [445.701404] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [445.701491] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [445.701586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [445.701681] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [445.701778] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [445.701861] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [445.701944] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [445.702027] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [445.702108] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [445.702183] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [445.702259] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [445.702338] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [445.702416] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [445.702512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [445.702605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [445.702698] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [445.702786] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [445.702863] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [445.702941] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing DPLL 0
<7> [445.703044] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [445.703115] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [445.703197] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [445.703257] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [445.703317] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [445.703379] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [445.703440] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [445.703520] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [445.703604] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 0 num active planes 0
<7> [445.703707] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [445.703801] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [445.703885] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [445.703967] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [445.704048] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [445.704130] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [445.704222] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [445.704310] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [445.704390] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [445.704473] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [445.704581] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [445.704680] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [445.704777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [445.704858] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [445.704938] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [445.705019] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [445.705099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [445.705202] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [445.705379] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [445.705495] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [445.706103] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [445.706224] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [445.909675] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [445.910169] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [445.931348] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [445.931760] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [445.931960] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [445.932271] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [445.932434] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [445.983449] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [445.983757] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [445.983902] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [445.984673] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [445.984935] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [445.985021] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [445.985260] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [445.985620] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [445.985648] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [445.985851] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [445.986064] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [445.986270] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [445.986527] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [445.986729] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [445.986928] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [445.987182] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [445.987409] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [445.987631] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [445.987839] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [445.988066] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [445.988280] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [445.988524] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [445.988808] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [445.989047] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [445.989337] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [445.989802] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [445.990181] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [445.990619] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [445.990947] 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> [445.991338] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [445.991713] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [445.992855] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [445.993258] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [445.993329] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [445.993518] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [445.993935] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:387:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [445.994291] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [445.994656] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [445.994950] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [445.995277] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:387:pipe C]
<7> [445.995620] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [445.995937] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [445.996215] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [445.996522] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [445.996850] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [445.997123] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [445.997391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [445.997650] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [445.997880] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [445.998100] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [445.998317] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [445.998605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [445.998803] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [445.998996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [445.999179] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [445.999370] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [445.999550] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [445.999720] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [445.999892] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [446.000057] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [446.000216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [446.000376] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [446.000530] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [446.000680] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [446.000825] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [446.000970] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [446.001109] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [446.001247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [446.001391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [446.001523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [446.001649] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [446.001775] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [446.001903] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [446.002029] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [446.002151] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [446.002267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [446.002392] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [446.002510] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [446.002633] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [446.002783] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [446.002875] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [446.002990] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [446.003084] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [446.003172] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [446.003253] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [446.003336] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [446.003438] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [446.003536] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 1390840 num active planes 1
<7> [446.003650] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [446.003760] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [446.003867] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [446.003975] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [446.004078] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [446.004196] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [446.004320] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [446.004433] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [446.004531] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [446.004628] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [446.004732] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe C] allocated DPLL 0
<7> [446.004820] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe C] reserving DPLL 0
<7> [446.004913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [446.005007] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [446.005096] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [446.005183] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [446.005282] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [446.005441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [446.005540] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [446.005642] 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> [446.005728] 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> [446.005815] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [446.005908] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [446.005990] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [446.006069] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [446.006145] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [446.006222] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [446.006297] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [446.006386] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [446.006477] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [446.006572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [446.006662] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [446.006743] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [446.006820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [446.006896] 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: 104 vsync start: 103, vsync end: 97
<7> [446.006972] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [446.007045] 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> [446.007120] 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> [446.007200] 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> [446.007277] 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> [446.007349] 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> [446.007435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [446.007526] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [446.007616] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [446.007698] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [446.007773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [446.007856] 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> [446.007944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [446.008032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [446.008123] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [446.008199] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [446.008268] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [446.008337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [446.008437] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [446.008529] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [446.008624] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [446.008707] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [446.008783] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [446.008859] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [446.008934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009008] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009081] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009155] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [446.009228] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [446.009303] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009394] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.009577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [446.009662] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [446.009737] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [FB:433] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [446.009826] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [446.009914] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [446.010002] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [446.010083] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [446.010153] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [446.010220] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [446.010291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [446.010434] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [446.010687] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [446.010822] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [446.010956] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [446.011061] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [446.011169] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [446.011280] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [446.011433] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [446.011589] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [446.011752] 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> [446.011846] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [446.011935] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [446.012018] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [446.012089] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [446.012159] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [446.012223] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [446.012285] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [446.012364] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [446.012449] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [446.012532] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [446.012619] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [446.012705] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [446.012793] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [446.012879] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [446.012965] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [446.013066] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [446.013237] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [446.013350] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [446.014382] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [446.014488] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [446.016194] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [446.016279] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (468 ms remaining)
<7> [446.020302] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [446.020435] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [446.020519] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [446.020620] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [446.493471] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [446.584925] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [446.585390] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [446.585728] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [446.622288] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [446.622729] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [446.623095] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [446.623604] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [446.799222] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [446.799557] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [446.799821] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [446.801826] 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> [446.803107] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [446.803841] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [446.804928] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [446.806086] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [446.807404] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [446.807813] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [446.809527] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [446.809988] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [446.810870] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [446.811404] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [446.811774] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [446.812176] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 13892
<7> [446.813587] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [446.813946] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:271:plane 1C]
<7> [446.828207] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [446.829721] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [446.830127] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [449.356238] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [449.356596] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [453.116651] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [453.120663] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [453.133780] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:434] from all active usage due to RMFB ioctl
<7> [453.133944] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:434] is removed
<7> [453.134088] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [453.134497] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [453.134830] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [453.135218] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [453.135499] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [453.135861] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [453.136308] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [453.136655] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [453.137101] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [453.137466] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [453.137912] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [453.138951] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [453.139455] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [453.139820] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [453.143801] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:271:plane 1C]
<7> [453.173962] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [453.174383] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [453.174722] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [453.175096] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [453.175441] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [453.175789] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [453.176105] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [453.176427] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [453.176691] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [453.176943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149: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> [453.177184] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [453.177411] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [453.177657] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [453.177888] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [453.178096] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [453.178295] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [453.178507] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [453.178692] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [453.178934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [453.179132] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [453.179299] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [453.179465] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [453.179622] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [453.179778] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [453.179959] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [453.180116] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [453.180267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [453.180414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [453.180556] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [453.180694] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [453.180831] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [453.181011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [453.181159] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [453.181299] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [453.181420] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [453.181539] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [453.181654] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [453.181768] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [453.182048] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [453.182158] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [453.182263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [453.182365] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [453.182466] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [453.182576] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [453.182689] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [453.182796] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [453.182978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [453.183083] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [453.183172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [453.183262] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [453.183349] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [453.183434] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [453.183518] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [453.183600] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [453.183681] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [453.183760] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [453.183839] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [453.183943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [453.184034] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [453.184125] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [453.184206] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [453.184282] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [453.184356] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [453.184429] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [453.184500] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [453.184570] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [453.184639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [453.184711] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [453.184783] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [453.184861] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [453.184946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [453.185032] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [453.185118] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [453.185192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [453.185263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [453.185335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [453.185407] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [453.185478] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [453.185550] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [453.185616] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [453.185681] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [453.185746] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [453.185816] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [453.185907] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing DPLL 0
<7> [453.186057] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [453.186149] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [453.186223] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [453.186291] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [453.186358] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [453.186428] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [453.186488] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [453.186549] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [453.186608] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [453.186667] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [453.186738] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [453.186828] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [453.186946] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [453.187056] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [453.187154] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [453.187248] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [453.187333] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [453.187414] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [453.187536] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [453.187618] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [453.187703] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [453.187786] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [453.187872] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [453.187971] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [453.188067] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [453.188164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [453.188245] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [453.188323] 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> [453.188396] 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> [453.188467] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [453.188542] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [453.188619] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [453.188695] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [453.188772] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [453.188847] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [453.188951] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [453.189044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [453.189135] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [453.189216] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [453.189291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [453.189366] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [453.189440] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [453.189508] 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: 104 vsync start: 103, vsync end: 97
<7> [453.189576] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [453.189650] 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> [453.189726] 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> [453.189805] 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> [453.189899] 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> [453.189994] 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> [453.190095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [453.190194] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [453.190280] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [453.190356] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [453.190432] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [453.190501] 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> [453.190570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [453.190639] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [453.190713] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [453.190787] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [453.190866] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [453.190957] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [453.191045] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [453.191133] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [453.191215] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [453.191295] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [453.191379] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [453.191461] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [453.191539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.191606] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.191672] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.191742] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [453.191815] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [453.191926] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.192014] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.192100] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [453.192183] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [453.192255] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [453.192327] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:392] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [453.192401] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [453.192473] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [453.192546] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [453.192615] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [453.192680] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [453.192746] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [453.192816] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [453.192900] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [453.192974] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [453.193046] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [453.193119] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [453.193200] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [453.193275] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [453.193353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [453.193495] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [453.194414] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [453.194526] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [453.403037] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [453.403595] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [453.414351] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [453.414630] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [453.414945] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [453.414791] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [453.415820] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [453.467569] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [453.468084] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [453.474816] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [453.475239] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [453.475278] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [453.475815] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [453.476579] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [453.477079] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [453.477509] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [453.477835] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [453.478127] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [453.478407] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [453.478680] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [453.479045] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [453.479313] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [453.479576] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [453.479874] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [453.480160] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [453.480414] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [453.480670] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [453.481000] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [453.481315] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [453.481745] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [453.482071] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [453.482409] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [453.483904] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [453.484265] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [453.486309] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [453.486570] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (489 ms remaining)
<7> [453.502826] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [453.503273] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [453.503693] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [453.504150] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [453.978953] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [454.009728] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [454.010212] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [454.010723] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [454.047342] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [454.048046] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [454.048596] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [454.049315] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [454.217198] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [454.217604] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [454.217883] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [454.219965] 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> [454.221234] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [454.221940] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [454.223024] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [454.224125] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [454.225173] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [454.225492] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [454.227018] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [454.227334] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [454.228230] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [454.228655] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [454.229008] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [454.229395] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 13892
<7> [454.245600] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [454.247440] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [454.247877] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [454.250446] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [454.250895] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [454.251283] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [454.252186] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [454.253250] [IGT] kms_flip: exiting, ret=98
Created at 2025-12-19 00:03:16