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

Result: Fail

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

DetailValue
Duration 2.61 seconds
Hostname 
shard-lnl-4
Igt-Version 
IGT-Version: 2.3-geac3b04d1 (x86_64) (Linux: 7.0.0-rc2-lgci-xe-xe-pw-153280v4-debug+ x86_64)
Out 
Using IGT_SRANDOM=1772878069 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-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 A-eDP1: FAIL (2.612s)
Err 
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:5343) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:5343) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5343) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 19: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 20: 0x0000004c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 21: 0x0000024c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 22: 0x0000004e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 23: 0x0000004f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 24: 0x0000008c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 25: 0x0000028c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 26: 0x0000008e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 27: 0x0000008f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 28: 0x000000cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 29: 0x000002cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 30: 0x000000ce
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 31: 0x000000cf
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 7: 0x00000033
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 8: 0x00000030
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 9: 0x0000020c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 10: 0x00000230
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 11: 0x0000061c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 12: 0x0000023c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 13: 0x00000000
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 14: 0x00000200
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 15: 0x00000614
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 16: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 17: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 18: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 19: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 20: 0x0000004c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 21: 0x0000024c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 22: 0x0000004e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 23: 0x0000004f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 24: 0x0000008c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 25: 0x0000028c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 26: 0x0000008e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 27: 0x0000008f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 28: 0x000000cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 29: 0x000002cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 30: 0x000000ce
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 31: 0x000000cf
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 0: 0x0000000c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 1: 0x0000000e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 2: 0x0000000f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 3: 0x0000003c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 4: 0x00000032
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 5: 0x0000003e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 6: 0x0000041c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 7: 0x00000033
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 8: 0x00000030
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 9: 0x0000020c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 10: 0x00000230
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 11: 0x0000061c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 12: 0x0000023c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 13: 0x00000000
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 14: 0x00000200
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 15: 0x00000614
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 16: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 17: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 18: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 19: 0x00000000 (reserved)
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 20: 0x0000004c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 21: 0x0000024c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 22: 0x0000004e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 23: 0x0000004f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 24: 0x0000008c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 25: 0x0000028c
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 26: 0x0000008e
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 27: 0x0000008f
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 28: 0x000000cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 29: 0x000002cc
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 30: 0x000000ce
(kms_flip:5343) intel_pat-DEBUG: Parsed PAT entry 31: 0x000000cf
(kms_flip:5343) intel_allocator_simple-DEBUG: <ial: 0x61b58bfc2450, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:5343) intel_allocator_simple-DEBUG: <ial: 0x61b58bfc2450, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:5343) igt_kms-INFO:   2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
(kms_flip:5343) DEBUG: No stale events found
(kms_flip:5343) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:5343) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:5343) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 294.707184
current_received_ts = 294.706390
current_seq = 6214
count = 0
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 141us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.707184
last_received_ts = 294.706390
last_seq = 6214
current_ts = 294.723846
current_received_ts = 294.723053
current_seq = 6215
count = 1
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 66us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.723846
last_received_ts = 294.723053
last_seq = 6215
current_ts = 294.740509
current_received_ts = 294.739746
current_seq = 6216
count = 2
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.740509
last_received_ts = 294.739746
last_seq = 6216
current_ts = 294.757172
current_received_ts = 294.756378
current_seq = 6217
count = 3
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 63us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.757172
last_received_ts = 294.756378
last_seq = 6217
current_ts = 294.773865
current_received_ts = 294.773041
current_seq = 6218
count = 4
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.773865
last_received_ts = 294.773041
last_seq = 6218
current_ts = 294.790527
current_received_ts = 294.789734
current_seq = 6219
count = 5
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.790527
last_received_ts = 294.789734
last_seq = 6219
current_ts = 294.807190
current_received_ts = 294.806366
current_seq = 6220
count = 6
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 49us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.807190
last_received_ts = 294.806366
last_seq = 6220
current_ts = 294.823853
current_received_ts = 294.823059
current_seq = 6221
count = 7
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.823853
last_received_ts = 294.823059
last_seq = 6221
current_ts = 294.840515
current_received_ts = 294.839722
current_seq = 6222
count = 8
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 45us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.840515
last_received_ts = 294.839722
last_seq = 6222
current_ts = 294.857178
current_received_ts = 294.856354
current_seq = 6223
count = 9
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 44us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.857178
last_received_ts = 294.856354
last_seq = 6223
current_ts = 294.873840
current_received_ts = 294.873047
current_seq = 6224
count = 10
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.873840
last_received_ts = 294.873047
last_seq = 6224
current_ts = 294.890503
current_received_ts = 294.889709
current_seq = 6225
count = 11
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.890503
last_received_ts = 294.889709
last_seq = 6225
current_ts = 294.907166
current_received_ts = 294.906342
current_seq = 6226
count = 12
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.907166
last_received_ts = 294.906342
last_seq = 6226
current_ts = 294.923828
current_received_ts = 294.923065
current_seq = 6227
count = 13
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 58us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.923828
last_received_ts = 294.923065
last_seq = 6227
current_ts = 294.940491
current_received_ts = 294.939728
current_seq = 6228
count = 14
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.940491
last_received_ts = 294.939728
last_seq = 6228
current_ts = 294.957153
current_received_ts = 294.956360
current_seq = 6229
count = 15
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 57us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.957153
last_received_ts = 294.956360
last_seq = 6229
current_ts = 294.973846
current_received_ts = 294.973053
current_seq = 6230
count = 16
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.973846
last_received_ts = 294.973053
last_seq = 6230
current_ts = 294.990509
current_received_ts = 294.989746
current_seq = 6231
count = 17
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 64us
(kms_flip:5343) DEBUG: name = flip
last_ts = 294.990509
last_received_ts = 294.989746
last_seq = 6231
current_ts = 295.007172
current_received_ts = 295.006378
current_seq = 6232
count = 18
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 92us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.007172
last_received_ts = 295.006378
last_seq = 6232
current_ts = 295.023834
current_received_ts = 295.023041
current_seq = 6233
count = 19
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 73us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.023834
last_received_ts = 295.023041
last_seq = 6233
current_ts = 295.040497
current_received_ts = 295.039703
current_seq = 6234
count = 20
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 57us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.040497
last_received_ts = 295.039703
last_seq = 6234
current_ts = 295.057159
current_received_ts = 295.056366
current_seq = 6235
count = 21
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 55us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.057159
last_received_ts = 295.056366
last_seq = 6235
current_ts = 295.073822
current_received_ts = 295.073029
current_seq = 6236
count = 22
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 65us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.073822
last_received_ts = 295.073029
last_seq = 6236
current_ts = 295.090485
current_received_ts = 295.089691
current_seq = 6237
count = 23
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 44us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.090485
last_received_ts = 295.089691
last_seq = 6237
current_ts = 295.107147
current_received_ts = 295.106354
current_seq = 6238
count = 24
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 67us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.107147
last_received_ts = 295.106354
last_seq = 6238
current_ts = 295.123810
current_received_ts = 295.123047
current_seq = 6239
count = 25
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 60us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.123810
last_received_ts = 295.123047
last_seq = 6239
current_ts = 295.140472
current_received_ts = 295.139679
current_seq = 6240
count = 26
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 49us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.140472
last_received_ts = 295.139679
last_seq = 6240
current_ts = 295.157135
current_received_ts = 295.156342
current_seq = 6241
count = 27
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 49us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.157135
last_received_ts = 295.156342
last_seq = 6241
current_ts = 295.173798
current_received_ts = 295.173004
current_seq = 6242
count = 28
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.173798
last_received_ts = 295.173004
last_seq = 6242
current_ts = 295.190491
current_received_ts = 295.189697
current_seq = 6243
count = 29
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 70us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.190491
last_received_ts = 295.189697
last_seq = 6243
current_ts = 295.207153
current_received_ts = 295.206329
current_seq = 6244
count = 30
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 41us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.207153
last_received_ts = 295.206329
last_seq = 6244
current_ts = 295.223816
current_received_ts = 295.223022
current_seq = 6245
count = 31
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.223816
last_received_ts = 295.223022
last_seq = 6245
current_ts = 295.240479
current_received_ts = 295.239685
current_seq = 6246
count = 32
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 59us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.240479
last_received_ts = 295.239685
last_seq = 6246
current_ts = 295.257141
current_received_ts = 295.256317
current_seq = 6247
count = 33
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 55us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.257141
last_received_ts = 295.256317
last_seq = 6247
current_ts = 295.273804
current_received_ts = 295.273041
current_seq = 6248
count = 34
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.273804
last_received_ts = 295.273041
last_seq = 6248
current_ts = 295.290466
current_received_ts = 295.289673
current_seq = 6249
count = 35
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.290466
last_received_ts = 295.289673
last_seq = 6249
current_ts = 295.307129
current_received_ts = 295.306305
current_seq = 6250
count = 36
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.307129
last_received_ts = 295.306305
last_seq = 6250
current_ts = 295.323792
current_received_ts = 295.323029
current_seq = 6251
count = 37
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 56us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.323792
last_received_ts = 295.323029
last_seq = 6251
current_ts = 295.340454
current_received_ts = 295.339691
current_seq = 6252
count = 38
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 49us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.340454
last_received_ts = 295.339691
last_seq = 6252
current_ts = 295.357117
current_received_ts = 295.356323
current_seq = 6253
count = 39
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.357117
last_received_ts = 295.356323
last_seq = 6253
current_ts = 295.373779
current_received_ts = 295.373016
current_seq = 6254
count = 40
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.373779
last_received_ts = 295.373016
last_seq = 6254
current_ts = 295.390442
current_received_ts = 295.389679
current_seq = 6255
count = 41
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.390442
last_received_ts = 295.389679
last_seq = 6255
current_ts = 295.407135
current_received_ts = 295.406311
current_seq = 6256
count = 42
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 41us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.407135
last_received_ts = 295.406311
last_seq = 6256
current_ts = 295.423798
current_received_ts = 295.423004
current_seq = 6257
count = 43
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.423798
last_received_ts = 295.423004
last_seq = 6257
current_ts = 295.440460
current_received_ts = 295.439667
current_seq = 6258
count = 44
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.440460
last_received_ts = 295.439667
last_seq = 6258
current_ts = 295.457123
current_received_ts = 295.456299
current_seq = 6259
count = 45
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.457123
last_received_ts = 295.456299
last_seq = 6259
current_ts = 295.473785
current_received_ts = 295.472992
current_seq = 6260
count = 46
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 66us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.473785
last_received_ts = 295.472992
last_seq = 6260
current_ts = 295.490448
current_received_ts = 295.489655
current_seq = 6261
count = 47
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 55us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.490448
last_received_ts = 295.489655
last_seq = 6261
current_ts = 295.507111
current_received_ts = 295.506317
current_seq = 6262
count = 48
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 38us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.507111
last_received_ts = 295.506317
last_seq = 6262
current_ts = 295.523773
current_received_ts = 295.522980
current_seq = 6263
count = 49
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 68us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.523773
last_received_ts = 295.522980
last_seq = 6263
current_ts = 295.540436
current_received_ts = 295.539612
current_seq = 6264
count = 50
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 54us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.540436
last_received_ts = 295.539612
last_seq = 6264
current_ts = 295.557098
current_received_ts = 295.556305
current_seq = 6265
count = 51
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.557098
last_received_ts = 295.556305
last_seq = 6265
current_ts = 295.573761
current_received_ts = 295.572998
current_seq = 6266
count = 52
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 68us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.573761
last_received_ts = 295.572998
last_seq = 6266
current_ts = 295.590424
current_received_ts = 295.589661
current_seq = 6267
count = 53
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 58us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.590424
last_received_ts = 295.589661
last_seq = 6267
current_ts = 295.607086
current_received_ts = 295.606293
current_seq = 6268
count = 54
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 59us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.607086
last_received_ts = 295.606293
last_seq = 6268
current_ts = 295.623779
current_received_ts = 295.622986
current_seq = 6269
count = 55
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 65us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.623779
last_received_ts = 295.622986
last_seq = 6269
current_ts = 295.640442
current_received_ts = 295.639648
current_seq = 6270
count = 56
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.640442
last_received_ts = 295.639648
last_seq = 6270
current_ts = 295.657104
current_received_ts = 295.656281
current_seq = 6271
count = 57
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.657104
last_received_ts = 295.656281
last_seq = 6271
current_ts = 295.673767
current_received_ts = 295.672974
current_seq = 6272
count = 58
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 71us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.673767
last_received_ts = 295.672974
last_seq = 6272
current_ts = 295.690430
current_received_ts = 295.689606
current_seq = 6273
count = 59
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.690430
last_received_ts = 295.689606
last_seq = 6273
current_ts = 295.707092
current_received_ts = 295.706268
current_seq = 6274
count = 60
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 55us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.707092
last_received_ts = 295.706268
last_seq = 6274
current_ts = 295.723755
current_received_ts = 295.722961
current_seq = 6275
count = 61
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 88us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.723755
last_received_ts = 295.722961
last_seq = 6275
current_ts = 295.740417
current_received_ts = 295.739624
current_seq = 6276
count = 62
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 52us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.740417
last_received_ts = 295.739624
last_seq = 6276
current_ts = 295.757080
current_received_ts = 295.756256
current_seq = 6277
count = 63
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.757080
last_received_ts = 295.756256
last_seq = 6277
current_ts = 295.773743
current_received_ts = 295.772949
current_seq = 6278
count = 64
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 76us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.773743
last_received_ts = 295.772949
last_seq = 6278
current_ts = 295.790405
current_received_ts = 295.789642
current_seq = 6279
count = 65
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 50us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.790405
last_received_ts = 295.789642
last_seq = 6279
current_ts = 295.807068
current_received_ts = 295.806274
current_seq = 6280
count = 66
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.807068
last_received_ts = 295.806274
last_seq = 6280
current_ts = 295.823730
current_received_ts = 295.822968
current_seq = 6281
count = 67
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 59us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.823730
last_received_ts = 295.822968
last_seq = 6281
current_ts = 295.840424
current_received_ts = 295.839630
current_seq = 6282
count = 68
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 48us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.840424
last_received_ts = 295.839630
last_seq = 6282
current_ts = 295.857086
current_received_ts = 295.856262
current_seq = 6283
count = 69
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 58us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.857086
last_received_ts = 295.856262
last_seq = 6283
current_ts = 295.873749
current_received_ts = 295.872925
current_seq = 6284
count = 70
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.873749
last_received_ts = 295.872925
last_seq = 6284
current_ts = 295.890411
current_received_ts = 295.889618
current_seq = 6285
count = 71
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 53us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.890411
last_received_ts = 295.889618
last_seq = 6285
current_ts = 295.907074
current_received_ts = 295.906250
current_seq = 6286
count = 72
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 51us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.907074
last_received_ts = 295.906250
last_seq = 6286
current_ts = 295.923737
current_received_ts = 295.922974
current_seq = 6287
count = 73
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 77us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.923737
last_received_ts = 295.922974
last_seq = 6287
current_ts = 295.940399
current_received_ts = 295.939606
current_seq = 6288
count = 74
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 68us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.940399
last_received_ts = 295.939606
last_seq = 6288
current_ts = 295.957062
current_received_ts = 295.956268
current_seq = 6289
count = 75
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 62us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.957062
last_received_ts = 295.956268
last_seq = 6289
current_ts = 295.973724
current_received_ts = 295.972931
current_seq = 6290
count = 76
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 76us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.973724
last_received_ts = 295.972931
last_seq = 6290
current_ts = 295.990387
current_received_ts = 295.989624
current_seq = 6291
count = 77
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 60us
(kms_flip:5343) DEBUG: name = flip
last_ts = 295.990387
last_received_ts = 295.989624
last_seq = 6291
current_ts = 296.007050
current_received_ts = 296.006256
current_seq = 6292
count = 78
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 59us
(kms_flip:5343) DEBUG: name = flip
last_ts = 296.007050
last_received_ts = 296.006256
last_seq = 6292
current_ts = 296.023712
current_received_ts = 296.022949
current_seq = 6293
count = 79
seq_step = 1
(kms_flip:5343) DEBUG: Vblank took 876us
(kms_flip:5343) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:5343) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5343) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:5343) igt_core-INFO: Stack trace:
(kms_flip:5343) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:5343) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:5343) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:5343) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:5343) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:5343) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:5343) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:5343) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (2.612s)
Dmesg
<6> [293.058103] Console: switching to colour dummy device 80x25
<6> [293.058434] [IGT] kms_flip: executing
<7> [293.064242] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:401:DP-1]
<7> [293.064251] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:401:DP-1]
<7> [293.064426] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [293.064541] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:401:DP-1] disconnected
<7> [293.064787] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:413:DP-2]
<7> [293.064790] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:413:DP-2]
<7> [293.064925] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [293.065016] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:413:DP-2] disconnected
<7> [293.065285] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:421:DP-3]
<7> [293.065289] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:421:DP-3]
<7> [293.065431] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [293.065533] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:421:DP-3] disconnected
<7> [293.065810] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:392:eDP-1]
<7> [293.065813] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:392:eDP-1]
<7> [293.066267] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [293.066582] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [293.066690] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [293.066796] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [293.066914] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:392:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<5> [293.066919] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [293.066921] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [293.066922] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:392:eDP-1] DisplayID extension version 0x13, primary use 0x00
<7> [293.066924] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:392:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<5> [293.066926] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [293.066927] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:392:eDP-1] ELD monitor
<5> [293.066929] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [293.066930] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:392:eDP-1] ELD size 20, SAD count 0
<5> [293.066932] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [293.066942] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:392:eDP-1] VRR capable: yes
<7> [293.067022] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:392:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [293.067100] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:392:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<5> [293.067415] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [293.067418] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [293.067420] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [293.067507] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:392:eDP-1] probed modes:
<7> [293.067511] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [293.067517] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 120 695310 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [293.067537] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:401:DP-1]
<7> [293.067542] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:401:DP-1]
<7> [293.067750] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:401:DP-1] disconnected
<7> [293.067765] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:413:DP-2]
<7> [293.067769] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:413:DP-2]
<7> [293.067947] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:413:DP-2] disconnected
<7> [293.067961] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:421:DP-3]
<7> [293.067964] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:421:DP-3]
<7> [293.068143] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:421:DP-3] disconnected
<6> [293.072448] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [293.073714] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [293.080483] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [293.080857] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [293.136850] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [293.136934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [293.137064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [293.137161] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [293.137273] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [293.137377] 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 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [293.137498] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [293.137617] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [293.137717] 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 2880, found 0)
<7> [293.137809] 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 3040, found 0)
<7> [293.137906] 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 2880, found 0)
<7> [293.137999] 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 3040, found 0)
<7> [293.138091] 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 2928, found 0)
<7> [293.138181] 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 2960, found 0)
<7> [293.138300] 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 1800, found 0)
<7> [293.138407] 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 1800, found 0)
<7> [293.138511] 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 1803, found 0)
<7> [293.138599] 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 1809, found 0)
<7> [293.138677] 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 1906, found 0)
<7> [293.138755] 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 1906, found 0)
<7> [293.138832] 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 2880, found 0)
<7> [293.138911] 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 3040, found 0)
<7> [293.138994] 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 2880, found 0)
<7> [293.139076] 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 3040, found 0)
<7> [293.139158] 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 2928, found 0)
<7> [293.139241] 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 2960, found 0)
<7> [293.139335] 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 1800, found 0)
<7> [293.139432] 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 1800, found 0)
<7> [293.139528] 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 1803, found 0)
<7> [293.139606] 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 1809, found 0)
<7> [293.139679] 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 1906, found 0)
<7> [293.139752] 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 1906, found 0)
<7> [293.139825] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [293.139901] 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 2, found 0)
<7> [293.139980] 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 8, found 0)
<7> [293.140060] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [293.140139] 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 347710, found 0)
<7> [293.140212] 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 347710, found 0)
<7> [293.140308] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [293.140407] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [293.140506] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:150:pipe A] releasing DPLL 0
<7> [293.140626] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [293.140686] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [293.140761] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [293.140825] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [293.140889] 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> [293.140947] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] lines 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [293.141005] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] blocks 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [293.141065] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] min_ddb 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [293.141151] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 0 num active planes 0
<7> [293.141264] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [293.141370] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [293.141474] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [293.141565] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [293.141645] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [293.141725] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [293.141820] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [293.141909] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [293.141999] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [293.142088] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [293.142198] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: no [modeset]
<7> [293.142299] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [293.142401] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [293.142502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [293.142591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [293.142671] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [293.142746] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [293.142874] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [293.142974] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [293.143114] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [293.147839] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [293.147992] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [293.353392] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 0
<7> [293.353945] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [293.364747] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power off
<7> [293.365075] 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> [293.365194] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [293.365443] 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> [293.365771] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [293.417774] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [293.418340] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [293.426554] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [293.426980] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:150:pipe A]
<7> [293.427172] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [293.427865] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [293.428556] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [293.429040] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [293.429584] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [293.429940] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [293.430286] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [293.430600] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [293.430904] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [293.431201] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [293.431603] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [293.431932] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [293.432232] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [293.432555] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [293.432834] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [293.433160] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [293.433540] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [293.433811] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [293.434187] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [293.434573] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [293.434839] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [293.435372] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [293.435759] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [293.436270] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [293.436603] 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> [293.437014] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [293.437344] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [293.438197] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [293.438615] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:388:pipe C]
<7> [293.438942] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [293.439011] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:392:eDP-1]
<7> [293.439194] 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> [293.439639] 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> [293.439954] 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> [293.440284] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [293.440604] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:391:DDI A/PHY A] [CRTC:150:pipe A]
<7> [293.440902] 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> [293.441175] 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> [293.441529] 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> [293.441802] 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> [293.442048] 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> [293.442308] 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> [293.442588] 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> [293.442818] 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> [293.443068] 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> [293.443308] 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> [293.443530] 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> [293.443718] 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> [293.443894] 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> [293.444068] 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> [293.444246] 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> [293.444409] 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> [293.444565] 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> [293.444718] 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> [293.444864] 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> [293.445034] 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> [293.445176] 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> [293.445391] 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> [293.445525] 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> [293.445677] 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> [293.445804] 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> [293.445927] 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> [293.446049] 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> [293.446167] 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> [293.446323] 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> [293.446459] 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> [293.446568] 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> [293.446679] 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> [293.446786] 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> [293.446897] 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> [293.447007] 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> [293.447112] 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> [293.447215] 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> [293.447340] 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> [293.447441] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [293.447582] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [293.447667] 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 0x0 -> 0x1
<7> [293.447772] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [293.447852] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [293.447919] 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> [293.447986] 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 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [293.448050] 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 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [293.448112] 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 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [293.448195] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 1390840 num active planes 1
<7> [293.448311] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [293.448419] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [293.448525] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [293.448613] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [293.448700] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [293.448785] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [293.448891] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [293.448976] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [293.449055] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [293.449133] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [293.449232] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated DPLL 0
<7> [293.449339] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving DPLL 0
<7> [293.449454] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [293.449559] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [293.449638] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [293.449715] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [293.449792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [293.449869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [293.449945] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [293.450021] 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> [293.450097] 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> [293.450179] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [293.450283] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [293.450384] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [293.450483] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [293.450572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [293.450651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [293.450725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [293.450799] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [293.450871] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [293.450944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [293.451017] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [293.451095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [293.451168] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [293.451250] 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> [293.451343] 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> [293.451435] 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> [293.451527] 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> [293.451603] 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> [293.451673] 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> [293.451744] 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> [293.451814] 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> [293.451884] 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> [293.451955] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [293.451956] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [293.452025] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [293.452095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [293.452126] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [293.452167] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [293.452231] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [293.452244] 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> [293.452337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [293.452337] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [293.452428] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [293.452520] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [293.452610] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [293.452691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [293.452765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [293.452833] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [293.452902] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [293.452975] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [293.453055] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [293.453135] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [293.453213] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [293.453321] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453414] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453507] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453599] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [293.453691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [293.453781] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453852] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453922] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [293.453992] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [293.454061] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [293.454131] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [293.454203] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [293.454296] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [293.454395] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [293.454499] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [293.454589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [293.454670] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [293.454744] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [293.455142] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [293.455288] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [293.455398] 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> [293.455497] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [293.455589] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [293.455671] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [293.455739] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [293.455806] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [293.455872] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [293.455935] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [293.455997] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [293.456064] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [293.456130] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [293.456195] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [293.456280] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [293.456357] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [293.456434] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [293.456510] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [293.456595] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [293.456753] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [293.456815] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [293.456920] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [293.458327] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [293.458460] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [293.460431] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power on
<7> [293.460532] 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> [293.929409] 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> [294.031232] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [294.031684] 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> [294.032090] 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> [294.068134] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [294.068715] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [294.069147] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [294.069589] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [294.073481] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [294.073576] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [294.073595] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [294.245355] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [294.245853] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [294.246334] 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> [294.248602] 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> [294.250165] 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> [294.251068] 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> [294.252279] 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> [294.253638] 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> [294.254794] 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> [294.255221] 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> [294.256710] 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> [294.257071] 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> [294.257907] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [294.258453] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [294.258842] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [294.259271] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 192000
<7> [294.275332] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [294.277164] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [294.277694] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [295.642276] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [295.642936] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [295.643585] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [295.688522] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2026-03-07 10:50:12