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

Result: Fail

i915_display_info12 igt_runner12 results12.json results12-xe-load.json guc_logs12.tar i915_display_info_post_exec12 boot12 dmesg12

DetailValue
Duration 10.35 seconds
Hostname 
shard-lnl-5
Igt-Version 
IGT-Version: 2.3-gdbe2c5702 (x86_64) (Linux: 6.19.0-lgci-xe-xe-4569-e66c64e4f12e55cad-debug+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (10.346s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (26.638s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2351 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:3771) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3771) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3771) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:3771) DEBUG: Vblank took 60us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.414413
last_received_ts = 186.413605
last_seq = 1116
current_ts = 186.431076
current_received_ts = 186.430252
current_seq = 1117
count = 427
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 59us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.431076
last_received_ts = 186.430252
last_seq = 1117
current_ts = 186.447754
current_received_ts = 186.446930
current_seq = 1118
count = 428
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 77us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.447754
last_received_ts = 186.446930
last_seq = 1118
current_ts = 186.464401
current_received_ts = 186.463593
current_seq = 1119
count = 429
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.464401
last_received_ts = 186.463593
last_seq = 1119
current_ts = 186.481064
current_received_ts = 186.480255
current_seq = 1120
count = 430
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 60us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.481064
last_received_ts = 186.480255
last_seq = 1120
current_ts = 186.497742
current_received_ts = 186.496918
current_seq = 1121
count = 431
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.497742
last_received_ts = 186.496918
last_seq = 1121
current_ts = 186.514404
current_received_ts = 186.513672
current_seq = 1122
count = 432
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 87us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.514404
last_received_ts = 186.513672
last_seq = 1122
current_ts = 186.531067
current_received_ts = 186.530289
current_seq = 1123
count = 433
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 87us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.531067
last_received_ts = 186.530289
last_seq = 1123
current_ts = 186.547729
current_received_ts = 186.546936
current_seq = 1124
count = 434
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 73us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.547729
last_received_ts = 186.546936
last_seq = 1124
current_ts = 186.564392
current_received_ts = 186.563644
current_seq = 1125
count = 435
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 70us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.564392
last_received_ts = 186.563644
last_seq = 1125
current_ts = 186.581055
current_received_ts = 186.580261
current_seq = 1126
count = 436
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.581055
last_received_ts = 186.580261
last_seq = 1126
current_ts = 186.597717
current_received_ts = 186.596939
current_seq = 1127
count = 437
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 69us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.597717
last_received_ts = 186.596939
last_seq = 1127
current_ts = 186.614380
current_received_ts = 186.613602
current_seq = 1128
count = 438
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.614380
last_received_ts = 186.613602
last_seq = 1128
current_ts = 186.631058
current_received_ts = 186.630249
current_seq = 1129
count = 439
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 41us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.631058
last_received_ts = 186.630249
last_seq = 1129
current_ts = 186.647720
current_received_ts = 186.646942
current_seq = 1130
count = 440
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.647720
last_received_ts = 186.646942
last_seq = 1130
current_ts = 186.664383
current_received_ts = 186.663605
current_seq = 1131
count = 441
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.664383
last_received_ts = 186.663605
last_seq = 1131
current_ts = 186.681046
current_received_ts = 186.680237
current_seq = 1132
count = 442
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.681046
last_received_ts = 186.680237
last_seq = 1132
current_ts = 186.697708
current_received_ts = 186.696930
current_seq = 1133
count = 443
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 58us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.697708
last_received_ts = 186.696930
last_seq = 1133
current_ts = 186.714371
current_received_ts = 186.713593
current_seq = 1134
count = 444
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.714371
last_received_ts = 186.713593
last_seq = 1134
current_ts = 186.731033
current_received_ts = 186.730240
current_seq = 1135
count = 445
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.731033
last_received_ts = 186.730240
last_seq = 1135
current_ts = 186.747696
current_received_ts = 186.746918
current_seq = 1136
count = 446
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.747696
last_received_ts = 186.746918
last_seq = 1136
current_ts = 186.764359
current_received_ts = 186.763580
current_seq = 1137
count = 447
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 54us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.764359
last_received_ts = 186.763580
last_seq = 1137
current_ts = 186.781036
current_received_ts = 186.780228
current_seq = 1138
count = 448
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.781036
last_received_ts = 186.780228
last_seq = 1138
current_ts = 186.797699
current_received_ts = 186.796890
current_seq = 1139
count = 449
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.797699
last_received_ts = 186.796890
last_seq = 1139
current_ts = 186.814362
current_received_ts = 186.813568
current_seq = 1140
count = 450
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 67us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.814362
last_received_ts = 186.813568
last_seq = 1140
current_ts = 186.831024
current_received_ts = 186.830215
current_seq = 1141
count = 451
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 40us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.831024
last_received_ts = 186.830215
last_seq = 1141
current_ts = 186.847687
current_received_ts = 186.846909
current_seq = 1142
count = 452
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.847687
last_received_ts = 186.846909
last_seq = 1142
current_ts = 186.864349
current_received_ts = 186.863571
current_seq = 1143
count = 453
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.864349
last_received_ts = 186.863571
last_seq = 1143
current_ts = 186.881012
current_received_ts = 186.880219
current_seq = 1144
count = 454
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.881012
last_received_ts = 186.880219
last_seq = 1144
current_ts = 186.897675
current_received_ts = 186.896881
current_seq = 1145
count = 455
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.897675
last_received_ts = 186.896881
last_seq = 1145
current_ts = 186.914352
current_received_ts = 186.913559
current_seq = 1146
count = 456
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 48us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.914352
last_received_ts = 186.913559
last_seq = 1146
current_ts = 186.931015
current_received_ts = 186.930206
current_seq = 1147
count = 457
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.931015
last_received_ts = 186.930206
last_seq = 1147
current_ts = 186.947678
current_received_ts = 186.946884
current_seq = 1148
count = 458
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.947678
last_received_ts = 186.946884
last_seq = 1148
current_ts = 186.964340
current_received_ts = 186.963562
current_seq = 1149
count = 459
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 57us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.964340
last_received_ts = 186.963562
last_seq = 1149
current_ts = 186.981003
current_received_ts = 186.980194
current_seq = 1150
count = 460
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 58us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.981003
last_received_ts = 186.980194
last_seq = 1150
current_ts = 186.997665
current_received_ts = 186.996887
current_seq = 1151
count = 461
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 186.997665
last_received_ts = 186.996887
last_seq = 1151
current_ts = 187.014328
current_received_ts = 187.013550
current_seq = 1152
count = 462
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 46us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.014328
last_received_ts = 187.013550
last_seq = 1152
current_ts = 187.030991
current_received_ts = 187.030182
current_seq = 1153
count = 463
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 48us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.030991
last_received_ts = 187.030182
last_seq = 1153
current_ts = 187.047653
current_received_ts = 187.046844
current_seq = 1154
count = 464
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.047653
last_received_ts = 187.046844
last_seq = 1154
current_ts = 187.064316
current_received_ts = 187.063553
current_seq = 1155
count = 465
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.064316
last_received_ts = 187.063553
last_seq = 1155
current_ts = 187.080994
current_received_ts = 187.080185
current_seq = 1156
count = 466
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.080994
last_received_ts = 187.080185
last_seq = 1156
current_ts = 187.097656
current_received_ts = 187.096878
current_seq = 1157
count = 467
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 39us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.097656
last_received_ts = 187.096878
last_seq = 1157
current_ts = 187.114319
current_received_ts = 187.113541
current_seq = 1158
count = 468
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.114319
last_received_ts = 187.113541
last_seq = 1158
current_ts = 187.130981
current_received_ts = 187.130188
current_seq = 1159
count = 469
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.130981
last_received_ts = 187.130188
last_seq = 1159
current_ts = 187.147644
current_received_ts = 187.146835
current_seq = 1160
count = 470
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.147644
last_received_ts = 187.146835
last_seq = 1160
current_ts = 187.164307
current_received_ts = 187.163528
current_seq = 1161
count = 471
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.164307
last_received_ts = 187.163528
last_seq = 1161
current_ts = 187.180969
current_received_ts = 187.180161
current_seq = 1162
count = 472
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.180969
last_received_ts = 187.180161
last_seq = 1162
current_ts = 187.197632
current_received_ts = 187.196854
current_seq = 1163
count = 473
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 40us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.197632
last_received_ts = 187.196854
last_seq = 1163
current_ts = 187.214294
current_received_ts = 187.213531
current_seq = 1164
count = 474
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.214294
last_received_ts = 187.213531
last_seq = 1164
current_ts = 187.230972
current_received_ts = 187.230164
current_seq = 1165
count = 475
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.230972
last_received_ts = 187.230164
last_seq = 1165
current_ts = 187.247635
current_received_ts = 187.246857
current_seq = 1166
count = 476
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 57us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.247635
last_received_ts = 187.246857
last_seq = 1166
current_ts = 187.264297
current_received_ts = 187.263489
current_seq = 1167
count = 477
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.264297
last_received_ts = 187.263489
last_seq = 1167
current_ts = 187.280960
current_received_ts = 187.280151
current_seq = 1168
count = 478
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 74us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.280960
last_received_ts = 187.280151
last_seq = 1168
current_ts = 187.297623
current_received_ts = 187.296844
current_seq = 1169
count = 479
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 44us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.297623
last_received_ts = 187.296844
last_seq = 1169
current_ts = 187.314285
current_received_ts = 187.313507
current_seq = 1170
count = 480
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.314285
last_received_ts = 187.313507
last_seq = 1170
current_ts = 187.330963
current_received_ts = 187.330154
current_seq = 1171
count = 481
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.330963
last_received_ts = 187.330154
last_seq = 1171
current_ts = 187.347610
current_received_ts = 187.346832
current_seq = 1172
count = 482
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.347610
last_received_ts = 187.346832
last_seq = 1172
current_ts = 187.364273
current_received_ts = 187.363510
current_seq = 1173
count = 483
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.364273
last_received_ts = 187.363510
last_seq = 1173
current_ts = 187.380951
current_received_ts = 187.380142
current_seq = 1174
count = 484
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 48us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.380951
last_received_ts = 187.380142
last_seq = 1174
current_ts = 187.397614
current_received_ts = 187.396790
current_seq = 1175
count = 485
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 42us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.397614
last_received_ts = 187.396790
last_seq = 1175
current_ts = 187.414276
current_received_ts = 187.413498
current_seq = 1176
count = 486
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 54us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.414276
last_received_ts = 187.413498
last_seq = 1176
current_ts = 187.430939
current_received_ts = 187.430145
current_seq = 1177
count = 487
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 54us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.430939
last_received_ts = 187.430145
last_seq = 1177
current_ts = 187.447601
current_received_ts = 187.446823
current_seq = 1178
count = 488
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.447601
last_received_ts = 187.446823
last_seq = 1178
current_ts = 187.464264
current_received_ts = 187.463486
current_seq = 1179
count = 489
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 40us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.464264
last_received_ts = 187.463486
last_seq = 1179
current_ts = 187.480927
current_received_ts = 187.480133
current_seq = 1180
count = 490
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.480927
last_received_ts = 187.480133
last_seq = 1180
current_ts = 187.497589
current_received_ts = 187.496811
current_seq = 1181
count = 491
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 44us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.497589
last_received_ts = 187.496811
last_seq = 1181
current_ts = 187.514267
current_received_ts = 187.513458
current_seq = 1182
count = 492
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.514267
last_received_ts = 187.513458
last_seq = 1182
current_ts = 187.530930
current_received_ts = 187.530121
current_seq = 1183
count = 493
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 69us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.530930
last_received_ts = 187.530121
last_seq = 1183
current_ts = 187.547592
current_received_ts = 187.546814
current_seq = 1184
count = 494
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.547592
last_received_ts = 187.546814
last_seq = 1184
current_ts = 187.564255
current_received_ts = 187.563477
current_seq = 1185
count = 495
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.564255
last_received_ts = 187.563477
last_seq = 1185
current_ts = 187.580917
current_received_ts = 187.580124
current_seq = 1186
count = 496
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.580917
last_received_ts = 187.580124
last_seq = 1186
current_ts = 187.597580
current_received_ts = 187.596802
current_seq = 1187
count = 497
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.597580
last_received_ts = 187.596802
last_seq = 1187
current_ts = 187.614243
current_received_ts = 187.613464
current_seq = 1188
count = 498
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 41us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.614243
last_received_ts = 187.613464
last_seq = 1188
current_ts = 187.630905
current_received_ts = 187.630112
current_seq = 1189
count = 499
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.630905
last_received_ts = 187.630112
last_seq = 1189
current_ts = 187.647583
current_received_ts = 187.646774
current_seq = 1190
count = 500
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.647583
last_received_ts = 187.646774
last_seq = 1190
current_ts = 187.664230
current_received_ts = 187.663467
current_seq = 1191
count = 501
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 68us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.664230
last_received_ts = 187.663467
last_seq = 1191
current_ts = 187.680908
current_received_ts = 187.680099
current_seq = 1192
count = 502
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 53us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.680908
last_received_ts = 187.680099
last_seq = 1192
current_ts = 187.697571
current_received_ts = 187.696793
current_seq = 1193
count = 503
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 57us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.697571
last_received_ts = 187.696793
last_seq = 1193
current_ts = 187.714233
current_received_ts = 187.713440
current_seq = 1194
count = 504
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 48us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.714233
last_received_ts = 187.713440
last_seq = 1194
current_ts = 187.730896
current_received_ts = 187.730103
current_seq = 1195
count = 505
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.730896
last_received_ts = 187.730103
last_seq = 1195
current_ts = 187.747559
current_received_ts = 187.746780
current_seq = 1196
count = 506
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.747559
last_received_ts = 187.746780
last_seq = 1196
current_ts = 187.764221
current_received_ts = 187.763428
current_seq = 1197
count = 507
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.764221
last_received_ts = 187.763428
last_seq = 1197
current_ts = 187.780884
current_received_ts = 187.780075
current_seq = 1198
count = 508
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.780884
last_received_ts = 187.780075
last_seq = 1198
current_ts = 187.797546
current_received_ts = 187.796783
current_seq = 1199
count = 509
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.797546
last_received_ts = 187.796783
last_seq = 1199
current_ts = 187.814224
current_received_ts = 187.813461
current_seq = 1200
count = 510
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 95us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.814224
last_received_ts = 187.813461
last_seq = 1200
current_ts = 187.830887
current_received_ts = 187.830093
current_seq = 1201
count = 511
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 69us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.830887
last_received_ts = 187.830093
last_seq = 1201
current_ts = 187.847549
current_received_ts = 187.846771
current_seq = 1202
count = 512
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 43us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.847549
last_received_ts = 187.846771
last_seq = 1202
current_ts = 187.864212
current_received_ts = 187.863419
current_seq = 1203
count = 513
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 57us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.864212
last_received_ts = 187.863419
last_seq = 1203
current_ts = 187.880875
current_received_ts = 187.880081
current_seq = 1204
count = 514
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.880875
last_received_ts = 187.880081
last_seq = 1204
current_ts = 187.897537
current_received_ts = 187.896759
current_seq = 1205
count = 515
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 58us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.897537
last_received_ts = 187.896759
last_seq = 1205
current_ts = 187.914200
current_received_ts = 187.913406
current_seq = 1206
count = 516
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 58us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.914200
last_received_ts = 187.913406
last_seq = 1206
current_ts = 187.930862
current_received_ts = 187.930374
current_seq = 1207
count = 517
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 44us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.930862
last_received_ts = 187.930374
last_seq = 1207
current_ts = 187.947525
current_received_ts = 187.946747
current_seq = 1208
count = 518
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.947525
last_received_ts = 187.946747
last_seq = 1208
current_ts = 187.964203
current_received_ts = 187.963409
current_seq = 1209
count = 519
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.964203
last_received_ts = 187.963409
last_seq = 1209
current_ts = 187.980865
current_received_ts = 187.980072
current_seq = 1210
count = 520
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 60us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.980865
last_received_ts = 187.980072
last_seq = 1210
current_ts = 187.997528
current_received_ts = 187.996735
current_seq = 1211
count = 521
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 187.997528
last_received_ts = 187.996735
last_seq = 1211
current_ts = 188.014191
current_received_ts = 188.013565
current_seq = 1212
count = 522
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 69us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.014191
last_received_ts = 188.013565
last_seq = 1212
current_ts = 188.030853
current_received_ts = 188.030075
current_seq = 1213
count = 523
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 70us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.030853
last_received_ts = 188.030075
last_seq = 1213
current_ts = 188.047516
current_received_ts = 188.046722
current_seq = 1214
count = 524
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.047516
last_received_ts = 188.046722
last_seq = 1214
current_ts = 188.064178
current_received_ts = 188.063400
current_seq = 1215
count = 525
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 83us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.064178
last_received_ts = 188.063400
last_seq = 1215
current_ts = 188.080841
current_received_ts = 188.080063
current_seq = 1216
count = 526
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 55us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.080841
last_received_ts = 188.080063
last_seq = 1216
current_ts = 188.097504
current_received_ts = 188.096741
current_seq = 1217
count = 527
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 57us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.097504
last_received_ts = 188.096741
last_seq = 1217
current_ts = 188.114182
current_received_ts = 188.113388
current_seq = 1218
count = 528
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.114182
last_received_ts = 188.113388
last_seq = 1218
current_ts = 188.130844
current_received_ts = 188.130051
current_seq = 1219
count = 529
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 62us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.130844
last_received_ts = 188.130051
last_seq = 1219
current_ts = 188.147507
current_received_ts = 188.146729
current_seq = 1220
count = 530
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 56us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.147507
last_received_ts = 188.146729
last_seq = 1220
current_ts = 188.164169
current_received_ts = 188.163361
current_seq = 1221
count = 531
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 51us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.164169
last_received_ts = 188.163361
last_seq = 1221
current_ts = 188.180832
current_received_ts = 188.180054
current_seq = 1222
count = 532
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.180832
last_received_ts = 188.180054
last_seq = 1222
current_ts = 188.197495
current_received_ts = 188.196716
current_seq = 1223
count = 533
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.197495
last_received_ts = 188.196716
last_seq = 1223
current_ts = 188.214157
current_received_ts = 188.213364
current_seq = 1224
count = 534
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 49us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.214157
last_received_ts = 188.213364
last_seq = 1224
current_ts = 188.230820
current_received_ts = 188.230133
current_seq = 1225
count = 535
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 94us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.230820
last_received_ts = 188.230133
last_seq = 1225
current_ts = 188.247482
current_received_ts = 188.246704
current_seq = 1226
count = 536
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.247482
last_received_ts = 188.246704
last_seq = 1226
current_ts = 188.264160
current_received_ts = 188.263367
current_seq = 1227
count = 537
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 67us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.264160
last_received_ts = 188.263367
last_seq = 1227
current_ts = 188.280823
current_received_ts = 188.280060
current_seq = 1228
count = 538
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 61us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.280823
last_received_ts = 188.280060
last_seq = 1228
current_ts = 188.297485
current_received_ts = 188.296677
current_seq = 1229
count = 539
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.297485
last_received_ts = 188.296677
last_seq = 1229
current_ts = 188.314148
current_received_ts = 188.313339
current_seq = 1230
count = 540
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.314148
last_received_ts = 188.313339
last_seq = 1230
current_ts = 188.330811
current_received_ts = 188.330048
current_seq = 1231
count = 541
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 52us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.330811
last_received_ts = 188.330048
last_seq = 1231
current_ts = 188.347473
current_received_ts = 188.346710
current_seq = 1232
count = 542
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 63us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.347473
last_received_ts = 188.346710
last_seq = 1232
current_ts = 188.364136
current_received_ts = 188.363342
current_seq = 1233
count = 543
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 63us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.364136
last_received_ts = 188.363342
last_seq = 1233
current_ts = 188.380798
current_received_ts = 188.380035
current_seq = 1234
count = 544
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 44us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.380798
last_received_ts = 188.380035
last_seq = 1234
current_ts = 188.397461
current_received_ts = 188.396683
current_seq = 1235
count = 545
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 39us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.397461
last_received_ts = 188.396683
last_seq = 1235
current_ts = 188.414139
current_received_ts = 188.413345
current_seq = 1236
count = 546
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 50us
(kms_flip:3771) DEBUG: name = flip
last_ts = 188.414139
last_received_ts = 188.413345
last_seq = 1236
current_ts = 188.430801
current_received_ts = 188.429993
current_seq = 1237
count = 547
seq_step = 1
(kms_flip:3771) DEBUG: Vblank took 514us
(kms_flip:3771) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3771) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3771) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3771) igt_core-INFO: Stack trace:
(kms_flip:3771) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3771) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:3771) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:3771) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:3771) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:3771) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3771) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3771) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (10.346s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (26.638s)
(kms_flip:3771) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351:
(kms_flip:3771) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:3771) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:3771) igt_kms-CRITICAL: DPMS property not found on 392
Dmesg
<6> [178.096180] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [178.109156] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [178.109756] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:433]
<7> [178.160957] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [178.161048] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [178.161182] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [178.161408] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [178.161477] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [178.161597] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [178.161690] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [178.161776] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [178.161860] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269: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> [178.161946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [178.162031] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [178.162114] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [178.162197] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [178.162278] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [178.162417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [178.162538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [178.162645] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [178.162730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [178.162811] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [178.162890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [178.162966] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [178.163052] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [178.163128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [178.163203] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [178.163278] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [178.163382] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [178.163478] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [178.163571] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [178.163654] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [178.163728] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [178.163798] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [178.163868] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [178.163938] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [178.164008] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [178.164078] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [178.164147] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [178.164216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [178.164285] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [178.164387] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [178.164477] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [178.164567] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [178.164647] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [178.164722] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [178.164793] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe B] releasing DPLL 0
<7> [178.164889] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [178.164954] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [178.165039] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:153:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [178.165102] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:263:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [178.165164] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:153: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> [178.165218] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:153: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> [178.165271] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:153: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> [178.165345] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:153: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> [178.165432] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:269:pipe B] data rate 0 num active planes 0
<7> [178.165555] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [178.165666] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [178.165757] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [178.165836] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [178.165914] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [178.165996] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [178.166097] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [178.166213] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [178.166340] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [178.166445] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [178.166570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe B] enable: no [modeset]
<7> [178.166659] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [178.166738] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [178.166817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [178.166897] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [178.166975] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [178.167052] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [178.167168] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [178.167841] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [178.167964] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [178.168592] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [178.168710] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [178.376511] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 0
<7> [178.377127] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [178.387855] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power off
<7> [178.388332] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [178.388278] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [178.388725] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [178.388735] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [178.439860] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [178.440228] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [178.440262] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [178.440790] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [178.441064] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [178.441365] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [178.441754] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [178.442197] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [178.442737] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [178.443115] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [178.443495] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [178.443836] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [178.444212] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [178.444571] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [178.444860] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [178.445147] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [178.445444] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [178.445701] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [178.445981] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [178.446242] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [178.446562] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [178.446898] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [178.447336] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [178.447639] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [178.447933] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [178.448528] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [178.448931] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [178.449422] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [178.449920] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe B]
<7> [178.450279] 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> [178.450804] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [178.451142] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [178.452405] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:388:pipe C]
<7> [178.452828] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:388:pipe C]
<7> [178.452896] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:392:eDP-1]
<7> [178.453081] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:392:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [178.453481] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:391:DDI A/PHY A][CRTC:388:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [178.453787] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [178.454094] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [178.454355] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [178.454687] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:391:DDI A/PHY A] [CRTC:388:pipe C]
<7> [178.454979] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [178.455246] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [178.455545] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [178.455791] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [178.456046] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388: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> [178.456272] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [178.456532] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [178.456742] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [178.456971] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [178.457165] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [178.457375] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [178.457558] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [178.457737] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [178.457910] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [178.458081] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [178.458251] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [178.458468] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [178.458624] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [178.458775] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [178.458927] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [178.459074] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [178.459222] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [178.459390] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [178.459553] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [178.459715] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [178.459847] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [178.459994] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [178.460140] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [178.460264] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [178.460424] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [178.460560] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [178.460675] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [178.460786] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [178.460893] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [178.461003] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [178.461109] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [178.461215] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [178.461330] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [178.461436] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] fastset requirement not met, forcing full modeset
<7> [178.461559] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [178.461646] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:388:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [178.461762] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:272:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [178.461847] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:382:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [178.461921] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [178.461991] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [178.462061] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [178.462129] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [178.462207] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:388:pipe C] data rate 1390840 num active planes 1
<7> [178.462330] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [178.462433] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [178.462530] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [178.462625] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [178.462715] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [178.462823] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:388:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [178.462939] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [178.463037] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [178.463133] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [178.463215] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [178.463317] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:388:pipe C] allocated DPLL 0
<7> [178.463405] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:388:pipe C] reserving DPLL 0
<7> [178.463487] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:388:pipe C] enable: yes [modeset]
<7> [178.463572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [178.463656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [178.463732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [178.463809] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [178.463886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [178.463961] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [178.464035] 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> [178.464116] 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> [178.464194] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [178.464267] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [178.464355] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [178.464435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [178.464516] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [178.464595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [178.464674] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [178.464748] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [178.464820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [178.464890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [178.464962] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [178.465033] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [178.465103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [178.465174] 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> [178.465246] 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> [178.465329] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [178.465407] 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> [178.465500] 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> [178.465591] 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> [178.465679] 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> [178.465753] 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> [178.465823] 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> [178.465893] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [178.465962] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [178.466032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [178.466102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [178.466171] 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> [178.466241] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [178.466319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [178.466401] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [178.466482] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [178.466562] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [178.466642] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [178.466714] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [178.466786] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [178.466857] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [178.466928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [178.467000] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [178.467070] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [178.467141] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467211] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [178.467450] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [178.467533] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467620] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467705] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [178.467779] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [178.467848] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [178.467917] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:272:plane 1C] fb: [FB:432] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [178.467988] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [178.468057] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [178.468127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:302:plane 2C] fb: [NOFB], visible: no
<7> [178.468196] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:332:plane 3C] fb: [NOFB], visible: no
<7> [178.468265] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:362:plane 4C] fb: [NOFB], visible: no
<7> [178.468355] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:372:plane 5C] fb: [NOFB], visible: no
<7> [178.468441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:382:cursor C] fb: [NOFB], visible: no
<7> [178.468793] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [178.468911] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [178.469039] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [178.469134] 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> [178.469225] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [178.469319] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [178.469427] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [178.469513] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [178.469598] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [178.469665] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [178.469727] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [178.469787] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [178.469846] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [178.469905] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [178.469965] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [178.470025] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [178.470084] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [178.470146] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [178.470207] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [178.470434] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [178.470600] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [178.470710] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [178.472026] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:388:pipe C]
<7> [178.472103] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [178.474036] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power on
<7> [178.474105] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power cycle (467 ms remaining)
<7> [178.477454] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [178.477647] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [178.477747] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [178.477853] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [178.944538] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [178.975381] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [178.975759] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power on
<7> [178.976069] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [179.012369] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [179.012796] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [179.013120] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [179.013443] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [179.189870] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [179.190275] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [179.190719] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [179.192847] 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> [179.194461] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [179.195367] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [179.196619] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [179.198119] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [179.199434] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Clock recovery OK
<7> [179.199930] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [179.201632] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [179.202178] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [179.203121] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [179.203821] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [179.204343] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [179.204879] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 4518
<7> [179.206483] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [179.206861] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:272:plane 1C]
<7> [179.220597] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [179.222188] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [179.222698] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:388:pipe C]
<7> [181.768354] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD off
<7> [181.768805] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [188.442934] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [188.446936] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [188.459896] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:433] from all active usage due to RMFB ioctl
<7> [188.460768] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:272:plane 1C] because [FB:433] is removed
<7> [188.460926] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:272:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [188.461396] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [188.461749] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [188.462086] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [188.462582] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:272: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> [188.463052] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:388:pipe C] data rate 0 num active planes 0
<7> [188.463577] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [188.464040] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [188.464536] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [188.464976] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [188.465445] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [188.467931] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [188.468435] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [188.468795] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [188.485781] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:272:plane 1C]
<7> [188.514628] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:392:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [188.515213] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:391:DDI A/PHY A][CRTC:150:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [188.515725] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [188.516227] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [188.516736] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:391:DDI A/PHY A] [CRTC:150:pipe A]
<7> [188.517162] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [188.517562] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [188.517910] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [188.518334] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [188.518655] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150: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> [188.518963] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [188.519271] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [188.519552] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [188.519857] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [188.520111] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [188.520401] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [188.520633] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [188.520878] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [188.521091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [188.521331] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [188.521535] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [188.521744] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [188.521934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [188.522116] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [188.522328] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [188.522511] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [188.522717] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [188.522892] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [188.523053] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [188.523225] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [188.523379] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [188.523523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [188.523663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [188.523800] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [188.523934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [188.524085] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [188.524256] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [188.524386] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [188.524509] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [188.524628] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [188.524744] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [188.524860] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [188.524973] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [188.525083] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [188.525207] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [188.525315] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [188.525421] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [188.525521] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [188.525622] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388: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> [188.525720] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [188.525815] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [188.525909] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [188.526001] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [188.526091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [188.526291] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [188.526401] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [188.526506] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [188.526595] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [188.526681] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [188.526765] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [188.526847] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [188.526927] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [188.527007] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [188.527084] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [188.527170] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [188.527261] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [188.527352] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [188.527440] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [188.527522] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [188.527591] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [188.527659] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [188.527727] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [188.527794] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [188.527862] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [188.527929] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [188.527997] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [188.528064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [188.528131] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [188.528231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [188.528320] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [188.528408] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [188.528486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [188.528559] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] fastset requirement not met, forcing full modeset
<7> [188.528632] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:388:pipe C] releasing DPLL 0
<7> [188.528755] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [188.528822] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [188.528888] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:388:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [188.528954] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [188.529017] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [188.529071] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [188.529125] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [188.529207] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [188.529276] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [188.529345] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:382:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [188.529437] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 1390840 num active planes 1
<7> [188.529537] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [188.529619] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [188.529699] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [188.529778] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [188.529856] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [188.529934] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [188.530014] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:388:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [188.530131] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated DPLL 0
<7> [188.530237] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving DPLL 0
<7> [188.530337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [188.530443] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [188.530528] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [188.530613] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [188.530691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [188.530765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [188.530838] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [188.530910] 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> [188.530983] 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> [188.531054] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [188.531125] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [188.531224] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [188.531318] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [188.531413] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [188.531497] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [188.531577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [188.531652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [188.531723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [188.531795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [188.531865] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [188.531936] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [188.532008] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [188.532079] 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> [188.532154] 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> [188.532246] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [188.532339] 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> [188.532432] 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> [188.532511] 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> [188.532583] 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> [188.532654] 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> [188.532726] 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> [188.532799] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [188.532873] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [188.532945] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [188.533018] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [188.533090] 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> [188.533178] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [188.533272] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [188.533365] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [188.533455] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [188.533532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [188.533608] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [188.533679] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [188.533750] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [188.533820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [188.533891] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [188.533962] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [188.534032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [188.534101] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534190] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534282] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534372] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [188.534456] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [188.534531] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534602] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534671] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.534740] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [188.534809] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [188.534877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:393] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [188.534947] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [188.535016] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [188.535086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [188.535159] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [188.535248] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [188.535348] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [188.535450] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [188.535535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:388:pipe C] enable: no [modeset]
<7> [188.535605] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:272:plane 1C] fb: [NOFB], visible: no
<7> [188.535675] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:302:plane 2C] fb: [NOFB], visible: no
<7> [188.535750] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:332:plane 3C] fb: [NOFB], visible: no
<7> [188.535828] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:362:plane 4C] fb: [NOFB], visible: no
<7> [188.535916] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:372:plane 5C] fb: [NOFB], visible: no
<7> [188.536003] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:382:cursor C] fb: [NOFB], visible: no
<7> [188.536164] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [188.536347] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [188.536629] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [188.536745] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [188.744444] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 0
<7> [188.745027] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [188.755755] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power off
<7> [188.756405] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [188.756916] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [188.757472] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [188.757928] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [188.809934] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [188.810316] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [188.814184] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [188.814566] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:388:pipe C]
<7> [188.814592] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [188.815213] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [188.816121] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [188.816658] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [188.817212] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [188.817576] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [188.817922] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [188.818282] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [188.818620] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [188.818959] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [188.819320] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [188.819659] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [188.819990] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [188.820351] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [188.820681] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [188.821009] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [188.821360] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [188.821762] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [188.822323] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [188.822655] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [188.823037] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [188.824168] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [188.824541] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [188.826972] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power on
<7> [188.827311] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power cycle (488 ms remaining)
<7> [188.846536] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [188.846874] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [188.847196] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [188.847575] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [189.320218] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [189.380330] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [189.380804] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power on
<7> [189.381321] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [189.417392] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [189.417809] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [189.418209] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [189.418636] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [189.594068] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [189.595311] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [189.596213] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [189.599619] 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> [189.601610] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [189.603288] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [189.605326] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [189.607376] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [189.609226] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Clock recovery OK
<7> [189.610031] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [189.612190] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [189.612942] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:392:eDP-1][ENCODER:391:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [189.614242] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [189.615165] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [189.615726] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [189.616436] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 4518
<7> [189.632014] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [189.634524] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [189.635238] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [189.638849] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [189.639510] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [189.640085] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [189.640678] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:388:pipe C]
<7> [189.641915] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [189.643069] [IGT] kms_flip: exiting, ret=98
<7> [189.660138] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [189.660321] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [189.660442] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-02-17 21:41:07