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

Result: Fail

i915_display_info11 igt_runner11 results11.json results11-xe-load.json guc_logs11.tar i915_display_info_post_exec11 boot11 dmesg11

DetailValue
Duration 3.90 seconds
Hostname 
shard-lnl-3
Igt-Version 
IGT-Version: 2.3-g07ca77c0e (x86_64) (Linux: 6.19.0-lgci-xe-xe-4549-3d7aee9ade34df290-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:2120 __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 (3.901s)
Subtest flip-vs-expired-vblank: FAIL (26.377s)
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:3780) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3780) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:3780) DEBUG: name = flip
last_ts = 222.980469
last_received_ts = 222.979721
last_seq = 35
current_ts = 222.997131
current_received_ts = 222.996368
current_seq = 36
count = 32
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 222.997131
last_received_ts = 222.996368
last_seq = 36
current_ts = 223.013794
current_received_ts = 223.013062
current_seq = 37
count = 33
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.013794
last_received_ts = 223.013062
last_seq = 37
current_ts = 223.030472
current_received_ts = 223.029724
current_seq = 38
count = 34
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 52us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.030472
last_received_ts = 223.029724
last_seq = 38
current_ts = 223.047134
current_received_ts = 223.046371
current_seq = 39
count = 35
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.047134
last_received_ts = 223.046371
last_seq = 39
current_ts = 223.063797
current_received_ts = 223.063065
current_seq = 40
count = 36
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.063797
last_received_ts = 223.063065
last_seq = 40
current_ts = 223.080460
current_received_ts = 223.079727
current_seq = 41
count = 37
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 58us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.080460
last_received_ts = 223.079727
last_seq = 41
current_ts = 223.097122
current_received_ts = 223.096405
current_seq = 42
count = 38
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 46us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.097122
last_received_ts = 223.096405
last_seq = 42
current_ts = 223.113785
current_received_ts = 223.113052
current_seq = 43
count = 39
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.113785
last_received_ts = 223.113052
last_seq = 43
current_ts = 223.130447
current_received_ts = 223.129715
current_seq = 44
count = 40
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.130447
last_received_ts = 223.129715
last_seq = 44
current_ts = 223.147125
current_received_ts = 223.146362
current_seq = 45
count = 41
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.147125
last_received_ts = 223.146362
last_seq = 45
current_ts = 223.163788
current_received_ts = 223.163055
current_seq = 46
count = 42
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.163788
last_received_ts = 223.163055
last_seq = 46
current_ts = 223.180450
current_received_ts = 223.179703
current_seq = 47
count = 43
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 52us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.180450
last_received_ts = 223.179703
last_seq = 47
current_ts = 223.197113
current_received_ts = 223.196350
current_seq = 48
count = 44
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 58us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.197113
last_received_ts = 223.196350
last_seq = 48
current_ts = 223.213776
current_received_ts = 223.213043
current_seq = 49
count = 45
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 52us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.213776
last_received_ts = 223.213043
last_seq = 49
current_ts = 223.230453
current_received_ts = 223.229706
current_seq = 50
count = 46
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 52us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.230453
last_received_ts = 223.229706
last_seq = 50
current_ts = 223.247116
current_received_ts = 223.246353
current_seq = 51
count = 47
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.247116
last_received_ts = 223.246353
last_seq = 51
current_ts = 223.263779
current_received_ts = 223.263046
current_seq = 52
count = 48
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.263779
last_received_ts = 223.263046
last_seq = 52
current_ts = 223.280441
current_received_ts = 223.279709
current_seq = 53
count = 49
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.280441
last_received_ts = 223.279709
last_seq = 53
current_ts = 223.297119
current_received_ts = 223.296356
current_seq = 54
count = 50
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 53us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.297119
last_received_ts = 223.296356
last_seq = 54
current_ts = 223.313782
current_received_ts = 223.313034
current_seq = 55
count = 51
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.313782
last_received_ts = 223.313034
last_seq = 55
current_ts = 223.330444
current_received_ts = 223.329697
current_seq = 56
count = 52
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.330444
last_received_ts = 223.329697
last_seq = 56
current_ts = 223.347107
current_received_ts = 223.346405
current_seq = 57
count = 53
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.347107
last_received_ts = 223.346405
last_seq = 57
current_ts = 223.363770
current_received_ts = 223.363037
current_seq = 58
count = 54
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.363770
last_received_ts = 223.363037
last_seq = 58
current_ts = 223.380432
current_received_ts = 223.379700
current_seq = 59
count = 55
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.380432
last_received_ts = 223.379700
last_seq = 59
current_ts = 223.397110
current_received_ts = 223.396347
current_seq = 60
count = 56
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.397110
last_received_ts = 223.396347
last_seq = 60
current_ts = 223.413773
current_received_ts = 223.413025
current_seq = 61
count = 57
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.413773
last_received_ts = 223.413025
last_seq = 61
current_ts = 223.430435
current_received_ts = 223.429688
current_seq = 62
count = 58
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 43us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.430435
last_received_ts = 223.429688
last_seq = 62
current_ts = 223.447098
current_received_ts = 223.446381
current_seq = 63
count = 59
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 67us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.447098
last_received_ts = 223.446381
last_seq = 63
current_ts = 223.463760
current_received_ts = 223.463043
current_seq = 64
count = 60
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.463760
last_received_ts = 223.463043
last_seq = 64
current_ts = 223.480423
current_received_ts = 223.479706
current_seq = 65
count = 61
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.480423
last_received_ts = 223.479706
last_seq = 65
current_ts = 223.497101
current_received_ts = 223.496368
current_seq = 66
count = 62
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.497101
last_received_ts = 223.496368
last_seq = 66
current_ts = 223.513763
current_received_ts = 223.513046
current_seq = 67
count = 63
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.513763
last_received_ts = 223.513046
last_seq = 67
current_ts = 223.530426
current_received_ts = 223.529678
current_seq = 68
count = 64
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.530426
last_received_ts = 223.529678
last_seq = 68
current_ts = 223.547089
current_received_ts = 223.546341
current_seq = 69
count = 65
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 59us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.547089
last_received_ts = 223.546341
last_seq = 69
current_ts = 223.563751
current_received_ts = 223.563034
current_seq = 70
count = 66
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 40us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.563751
last_received_ts = 223.563034
last_seq = 70
current_ts = 223.580429
current_received_ts = 223.579712
current_seq = 71
count = 67
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.580429
last_received_ts = 223.579712
last_seq = 71
current_ts = 223.597076
current_received_ts = 223.596329
current_seq = 72
count = 68
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.597076
last_received_ts = 223.596329
last_seq = 72
current_ts = 223.613754
current_received_ts = 223.613022
current_seq = 73
count = 69
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.613754
last_received_ts = 223.613022
last_seq = 73
current_ts = 223.630417
current_received_ts = 223.629684
current_seq = 74
count = 70
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 62us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.630417
last_received_ts = 223.629684
last_seq = 74
current_ts = 223.647079
current_received_ts = 223.646332
current_seq = 75
count = 71
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.647079
last_received_ts = 223.646332
last_seq = 75
current_ts = 223.663742
current_received_ts = 223.663025
current_seq = 76
count = 72
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.663742
last_received_ts = 223.663025
last_seq = 76
current_ts = 223.680420
current_received_ts = 223.679672
current_seq = 77
count = 73
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 58us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.680420
last_received_ts = 223.679672
last_seq = 77
current_ts = 223.697083
current_received_ts = 223.696320
current_seq = 78
count = 74
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.697083
last_received_ts = 223.696320
last_seq = 78
current_ts = 223.713745
current_received_ts = 223.713028
current_seq = 79
count = 75
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.713745
last_received_ts = 223.713028
last_seq = 79
current_ts = 223.730408
current_received_ts = 223.729675
current_seq = 80
count = 76
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.730408
last_received_ts = 223.729675
last_seq = 80
current_ts = 223.747070
current_received_ts = 223.746338
current_seq = 81
count = 77
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 45us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.747070
last_received_ts = 223.746338
last_seq = 81
current_ts = 223.763733
current_received_ts = 223.763031
current_seq = 82
count = 78
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 62us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.763733
last_received_ts = 223.763031
last_seq = 82
current_ts = 223.780411
current_received_ts = 223.779663
current_seq = 83
count = 79
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 59us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.780411
last_received_ts = 223.779663
last_seq = 83
current_ts = 223.797073
current_received_ts = 223.796310
current_seq = 84
count = 80
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.797073
last_received_ts = 223.796310
last_seq = 84
current_ts = 223.813736
current_received_ts = 223.812988
current_seq = 85
count = 81
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.813736
last_received_ts = 223.812988
last_seq = 85
current_ts = 223.830399
current_received_ts = 223.829666
current_seq = 86
count = 82
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 44us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.830399
last_received_ts = 223.829666
last_seq = 86
current_ts = 223.847061
current_received_ts = 223.846329
current_seq = 87
count = 83
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.847061
last_received_ts = 223.846329
last_seq = 87
current_ts = 223.863724
current_received_ts = 223.863022
current_seq = 88
count = 84
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 40us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.863724
last_received_ts = 223.863022
last_seq = 88
current_ts = 223.880402
current_received_ts = 223.879654
current_seq = 89
count = 85
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.880402
last_received_ts = 223.879654
last_seq = 89
current_ts = 223.897064
current_received_ts = 223.896301
current_seq = 90
count = 86
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.897064
last_received_ts = 223.896301
last_seq = 90
current_ts = 223.913727
current_received_ts = 223.913025
current_seq = 91
count = 87
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.913727
last_received_ts = 223.913025
last_seq = 91
current_ts = 223.930389
current_received_ts = 223.929672
current_seq = 92
count = 88
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.930389
last_received_ts = 223.929672
last_seq = 92
current_ts = 223.947052
current_received_ts = 223.946335
current_seq = 93
count = 89
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 63us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.947052
last_received_ts = 223.946335
last_seq = 93
current_ts = 223.963715
current_received_ts = 223.962997
current_seq = 94
count = 90
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.963715
last_received_ts = 223.962997
last_seq = 94
current_ts = 223.980392
current_received_ts = 223.979675
current_seq = 95
count = 91
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 59us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.980392
last_received_ts = 223.979675
last_seq = 95
current_ts = 223.997055
current_received_ts = 223.996292
current_seq = 96
count = 92
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 223.997055
last_received_ts = 223.996292
last_seq = 96
current_ts = 224.013718
current_received_ts = 224.012970
current_seq = 97
count = 93
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 68us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.013718
last_received_ts = 224.012970
last_seq = 97
current_ts = 224.030380
current_received_ts = 224.029633
current_seq = 98
count = 94
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.030380
last_received_ts = 224.029633
last_seq = 98
current_ts = 224.047043
current_received_ts = 224.046265
current_seq = 99
count = 95
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 53us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.047043
last_received_ts = 224.046265
last_seq = 99
current_ts = 224.063721
current_received_ts = 224.062973
current_seq = 100
count = 96
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 72us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.063721
last_received_ts = 224.062973
last_seq = 100
current_ts = 224.080383
current_received_ts = 224.079636
current_seq = 101
count = 97
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.080383
last_received_ts = 224.079636
last_seq = 101
current_ts = 224.097046
current_received_ts = 224.096313
current_seq = 102
count = 98
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.097046
last_received_ts = 224.096313
last_seq = 102
current_ts = 224.113708
current_received_ts = 224.112976
current_seq = 103
count = 99
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 68us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.113708
last_received_ts = 224.112976
last_seq = 103
current_ts = 224.130371
current_received_ts = 224.129623
current_seq = 104
count = 100
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.130371
last_received_ts = 224.129623
last_seq = 104
current_ts = 224.147034
current_received_ts = 224.146301
current_seq = 105
count = 101
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.147034
last_received_ts = 224.146301
last_seq = 105
current_ts = 224.163712
current_received_ts = 224.162964
current_seq = 106
count = 102
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 66us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.163712
last_received_ts = 224.162964
last_seq = 106
current_ts = 224.180374
current_received_ts = 224.179626
current_seq = 107
count = 103
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.180374
last_received_ts = 224.179626
last_seq = 107
current_ts = 224.197037
current_received_ts = 224.196274
current_seq = 108
count = 104
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.197037
last_received_ts = 224.196274
last_seq = 108
current_ts = 224.213699
current_received_ts = 224.212997
current_seq = 109
count = 105
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.213699
last_received_ts = 224.212997
last_seq = 109
current_ts = 224.230377
current_received_ts = 224.229645
current_seq = 110
count = 106
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.230377
last_received_ts = 224.229645
last_seq = 110
current_ts = 224.247040
current_received_ts = 224.246277
current_seq = 111
count = 107
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.247040
last_received_ts = 224.246277
last_seq = 111
current_ts = 224.263702
current_received_ts = 224.262970
current_seq = 112
count = 108
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 72us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.263702
last_received_ts = 224.262970
last_seq = 112
current_ts = 224.280365
current_received_ts = 224.279633
current_seq = 113
count = 109
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.280365
last_received_ts = 224.279633
last_seq = 113
current_ts = 224.297028
current_received_ts = 224.296280
current_seq = 114
count = 110
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 45us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.297028
last_received_ts = 224.296280
last_seq = 114
current_ts = 224.313690
current_received_ts = 224.312973
current_seq = 115
count = 111
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 71us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.313690
last_received_ts = 224.312973
last_seq = 115
current_ts = 224.330353
current_received_ts = 224.329636
current_seq = 116
count = 112
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 61us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.330353
last_received_ts = 224.329636
last_seq = 116
current_ts = 224.347015
current_received_ts = 224.346283
current_seq = 117
count = 113
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.347015
last_received_ts = 224.346283
last_seq = 117
current_ts = 224.363693
current_received_ts = 224.362946
current_seq = 118
count = 114
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 67us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.363693
last_received_ts = 224.362946
last_seq = 118
current_ts = 224.380356
current_received_ts = 224.379608
current_seq = 119
count = 115
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.380356
last_received_ts = 224.379608
last_seq = 119
current_ts = 224.397018
current_received_ts = 224.396271
current_seq = 120
count = 116
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.397018
last_received_ts = 224.396271
last_seq = 120
current_ts = 224.413681
current_received_ts = 224.412979
current_seq = 121
count = 117
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 73us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.413681
last_received_ts = 224.412979
last_seq = 121
current_ts = 224.430359
current_received_ts = 224.429626
current_seq = 122
count = 118
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 62us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.430359
last_received_ts = 224.429626
last_seq = 122
current_ts = 224.447006
current_received_ts = 224.446289
current_seq = 123
count = 119
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.447006
last_received_ts = 224.446289
last_seq = 123
current_ts = 224.463684
current_received_ts = 224.462952
current_seq = 124
count = 120
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 67us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.463684
last_received_ts = 224.462952
last_seq = 124
current_ts = 224.480347
current_received_ts = 224.479614
current_seq = 125
count = 121
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.480347
last_received_ts = 224.479614
last_seq = 125
current_ts = 224.497009
current_received_ts = 224.496262
current_seq = 126
count = 122
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.497009
last_received_ts = 224.496262
last_seq = 126
current_ts = 224.513672
current_received_ts = 224.512955
current_seq = 127
count = 123
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 67us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.513672
last_received_ts = 224.512955
last_seq = 127
current_ts = 224.530350
current_received_ts = 224.529602
current_seq = 128
count = 124
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.530350
last_received_ts = 224.529602
last_seq = 128
current_ts = 224.546997
current_received_ts = 224.546249
current_seq = 129
count = 125
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.546997
last_received_ts = 224.546249
last_seq = 129
current_ts = 224.563675
current_received_ts = 224.562927
current_seq = 130
count = 126
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.563675
last_received_ts = 224.562927
last_seq = 130
current_ts = 224.580338
current_received_ts = 224.579605
current_seq = 131
count = 127
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.580338
last_received_ts = 224.579605
last_seq = 131
current_ts = 224.597000
current_received_ts = 224.596252
current_seq = 132
count = 128
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 58us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.597000
last_received_ts = 224.596252
last_seq = 132
current_ts = 224.613678
current_received_ts = 224.612930
current_seq = 133
count = 129
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 55us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.613678
last_received_ts = 224.612930
last_seq = 133
current_ts = 224.630325
current_received_ts = 224.629593
current_seq = 134
count = 130
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.630325
last_received_ts = 224.629593
last_seq = 134
current_ts = 224.647003
current_received_ts = 224.646194
current_seq = 135
count = 131
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.647003
last_received_ts = 224.646194
last_seq = 135
current_ts = 224.663666
current_received_ts = 224.662857
current_seq = 136
count = 132
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.663666
last_received_ts = 224.662857
last_seq = 136
current_ts = 224.680328
current_received_ts = 224.679504
current_seq = 137
count = 133
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.680328
last_received_ts = 224.679504
last_seq = 137
current_ts = 224.696991
current_received_ts = 224.696182
current_seq = 138
count = 134
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 44us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.696991
last_received_ts = 224.696182
last_seq = 138
current_ts = 224.713654
current_received_ts = 224.712845
current_seq = 139
count = 135
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.713654
last_received_ts = 224.712845
last_seq = 139
current_ts = 224.730331
current_received_ts = 224.729507
current_seq = 140
count = 136
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.730331
last_received_ts = 224.729507
last_seq = 140
current_ts = 224.746994
current_received_ts = 224.746170
current_seq = 141
count = 137
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.746994
last_received_ts = 224.746170
last_seq = 141
current_ts = 224.763657
current_received_ts = 224.762833
current_seq = 142
count = 138
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.763657
last_received_ts = 224.762833
last_seq = 142
current_ts = 224.780319
current_received_ts = 224.779587
current_seq = 143
count = 139
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 57us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.780319
last_received_ts = 224.779587
last_seq = 143
current_ts = 224.796997
current_received_ts = 224.796219
current_seq = 144
count = 140
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.796997
last_received_ts = 224.796219
last_seq = 144
current_ts = 224.813660
current_received_ts = 224.812912
current_seq = 145
count = 141
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.813660
last_received_ts = 224.812912
last_seq = 145
current_ts = 224.830322
current_received_ts = 224.829575
current_seq = 146
count = 142
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.830322
last_received_ts = 224.829575
last_seq = 146
current_ts = 224.846985
current_received_ts = 224.846222
current_seq = 147
count = 143
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 43us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.846985
last_received_ts = 224.846222
last_seq = 147
current_ts = 224.863647
current_received_ts = 224.862930
current_seq = 148
count = 144
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 59us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.863647
last_received_ts = 224.862930
last_seq = 148
current_ts = 224.880310
current_received_ts = 224.879593
current_seq = 149
count = 145
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.880310
last_received_ts = 224.879593
last_seq = 149
current_ts = 224.896973
current_received_ts = 224.896240
current_seq = 150
count = 146
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 59us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.896973
last_received_ts = 224.896240
last_seq = 150
current_ts = 224.913651
current_received_ts = 224.912918
current_seq = 151
count = 147
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 62us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.913651
last_received_ts = 224.912918
last_seq = 151
current_ts = 224.930313
current_received_ts = 224.929581
current_seq = 152
count = 148
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 60us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.930313
last_received_ts = 224.929581
last_seq = 152
current_ts = 224.946976
current_received_ts = 224.946213
current_seq = 153
count = 149
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 56us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.946976
last_received_ts = 224.946213
last_seq = 153
current_ts = 224.963638
current_received_ts = 224.962891
current_seq = 154
count = 150
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 58us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.963638
last_received_ts = 224.962891
last_seq = 154
current_ts = 224.980316
current_received_ts = 224.979645
current_seq = 155
count = 151
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 77us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.980316
last_received_ts = 224.979645
last_seq = 155
current_ts = 224.996964
current_received_ts = 224.996246
current_seq = 156
count = 152
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 78us
(kms_flip:3780) DEBUG: name = flip
last_ts = 224.996964
last_received_ts = 224.996246
last_seq = 156
current_ts = 225.013641
current_received_ts = 225.012985
current_seq = 157
count = 153
seq_step = 1
(kms_flip:3780) DEBUG: Vblank took 1281us
(kms_flip:3780) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:3780) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3780) igt_core-INFO: Stack trace:
(kms_flip:3780) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3780) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:3780) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:3780) igt_core-INFO:   #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
(kms_flip:3780) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:3780) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3780) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3780) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (3.901s)
Subtest flip-vs-expired-vblank: FAIL (26.377s)
(kms_flip:3780) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351:
(kms_flip:3780) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:3780) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:3780) igt_kms-CRITICAL: DPMS property not found on 392
Dmesg
<6> [221.028226] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [221.050001] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [221.050138] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [221.050236] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [221.050232] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [221.050610] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [221.128344] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [221.128595] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [221.128926] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [221.129411] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [221.129558] 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> [221.129808] 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> [221.130039] 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> [221.130259] 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> [221.130498] 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> [221.130710] 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> [221.130908] 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> [221.131104] 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> [221.131298] 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> [221.131502] 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> [221.131684] 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> [221.131859] 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> [221.132028] 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> [221.132219] 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> [221.132398] 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> [221.132564] 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> [221.132726] 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> [221.132881] 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> [221.133032] 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> [221.133183] 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> [221.133326] 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> [221.133533] 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> [221.133685] 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> [221.133822] 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> [221.133955] 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> [221.134104] 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> [221.134231] 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> [221.134367] 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> [221.134494] 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> [221.134622] 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> [221.134744] 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> [221.134861] 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> [221.134976] 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> [221.135091] 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> [221.135203] 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> [221.135314] 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> [221.135439] 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> [221.135546] 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> [221.135653] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [221.135761] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe B] releasing DPLL 0
<7> [221.135909] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [221.136009] 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> [221.136133] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:263:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [221.136245] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [221.136372] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [221.136480] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [221.136590] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [221.136725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe B] enable: no [modeset]
<7> [221.136833] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [221.136933] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [221.137032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [221.137126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [221.137221] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [221.137312] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [221.137576] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [221.137695] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [221.343551] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 0
<7> [221.344026] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [221.365316] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power off
<7> [221.365715] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [221.365901] 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> [221.366358] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [221.366375] 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> [221.418847] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [221.419550] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [221.425685] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [221.426082] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [221.426280] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [221.427100] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [221.427620] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [221.428095] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [221.428642] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [221.429012] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [221.429323] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [221.429680] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [221.430028] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [221.430315] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [221.430654] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [221.430933] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [221.431212] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [221.431525] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [221.431847] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [221.432121] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [221.432400] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [221.432678] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [221.433067] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [221.433348] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [221.433608] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [221.434161] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [221.434629] 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> [221.435106] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [221.435586] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe B]
<7> [221.435979] 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> [221.436449] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [221.436767] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [221.437717] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:388:pipe C]
<7> [221.438174] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:388:pipe C]
<7> [221.438258] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:392:eDP-1]
<7> [221.438491] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:392:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [221.438913] 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> [221.439271] 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> [221.439644] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [221.439916] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [221.440287] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:391:DDI A/PHY A] [CRTC:388:pipe C]
<7> [221.440652] 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> [221.440944] 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> [221.441226] 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> [221.441516] 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> [221.441828] 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> [221.442070] 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> [221.442312] 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> [221.442568] 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> [221.442785] 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> [221.442993] 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> [221.443188] 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> [221.443385] 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> [221.443570] 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> [221.443782] 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> [221.443971] 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> [221.444142] 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> [221.444340] 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> [221.444506] 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> [221.444661] 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> [221.444817] 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> [221.444970] 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> [221.445119] 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> [221.445263] 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> [221.445438] 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> [221.445598] 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> [221.445751] 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> [221.445883] 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> [221.446006] 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> [221.446125] 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> [221.446239] 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> [221.446356] 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> [221.446467] 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> [221.446590] 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> [221.446718] 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> [221.446827] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:388:pipe C] fastset requirement not met in dpll_hw_state
<7> [221.446928] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [221.447026] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [221.447122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [221.447215] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [221.447307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [221.447416] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [221.447508] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [221.447598] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [221.447687] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [221.447770] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [221.447856] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [221.447940] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [221.448024] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [221.448108] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [221.448189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [221.448273] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [221.448359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [221.448451] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [221.448540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [221.448628] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_hw_state: fracen: no,
<7> [221.448703] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] multiplier: 225, tx_clk_div: 1.
<7> [221.448775] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_rawhw_state:
<7> [221.448845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx: 0x10, cmn: 0x21
<7> [221.448914] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [221.448983] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [221.449052] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [221.449119] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [221.449192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [221.449270] 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> [221.449361] 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> [221.449460] 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> [221.449553] 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> [221.449644] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] fastset requirement not met, forcing full modeset
<7> [221.449735] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [221.449791] 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> [221.449866] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:272:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [221.449927] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:382:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [221.449983] 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> [221.450035] 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> [221.450087] 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> [221.450137] 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> [221.450202] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:388:pipe C] data rate 1390840 num active planes 1
<7> [221.450283] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [221.450382] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [221.450487] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [221.450589] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [221.450688] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [221.450805] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:388:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [221.450914] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [221.451001] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [221.451085] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [221.451185] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:388:pipe C] allocated DPLL 0
<7> [221.451260] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:388:pipe C] reserving DPLL 0
<7> [221.451341] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:388:pipe C] enable: yes [modeset]
<7> [221.451435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [221.451535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [221.451635] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [221.451722] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [221.451795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [221.451867] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [221.451940] 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> [221.452013] 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> [221.452085] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [221.452157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [221.452229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [221.452302] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [221.452435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [221.452535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [221.452634] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [221.452723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [221.452799] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [221.452871] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [221.452943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [221.453016] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [221.453087] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [221.453160] 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> [221.453232] 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> [221.453304] 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> [221.453397] 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> [221.453491] 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> [221.453581] 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> [221.453679] 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> [221.453769] 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> [221.453846] 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> [221.453919] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [221.453988] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [221.454059] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [221.454127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [221.454195] 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> [221.454261] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [221.454336] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [221.454423] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [221.454513] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [221.454607] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [221.454683] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [221.454754] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [221.454827] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [221.454898] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [221.454970] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [221.455046] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [221.455122] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [221.455198] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455274] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455357] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455370] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [221.455453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [221.455543] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [221.455545] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [221.455632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455674] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [221.455706] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455774] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.455800] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [221.455841] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [221.455910] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [221.455979] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:272:plane 1C] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [221.456048] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [221.456116] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [221.456184] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:302:plane 2C] fb: [NOFB], visible: no
<7> [221.456251] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:332:plane 3C] fb: [NOFB], visible: no
<7> [221.456320] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:362:plane 4C] fb: [NOFB], visible: no
<7> [221.456419] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:372:plane 5C] fb: [NOFB], visible: no
<7> [221.456516] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:382:cursor C] fb: [NOFB], visible: no
<7> [221.456917] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [221.457009] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [221.457245] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [221.457390] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [221.457534] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [221.457680] 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> [221.457771] 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> [221.457861] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [221.457853] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [221.457946] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [221.458026] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [221.458050] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [221.458092] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [221.458157] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [221.458155] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [221.458220] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [221.458285] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [221.458270] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [221.458361] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [221.458447] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [221.458529] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [221.458608] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [221.458676] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [221.458736] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [221.458793] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [221.458849] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [221.458926] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [221.459081] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [221.459183] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [221.460372] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:388:pipe C]
<7> [221.460501] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [221.462443] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power on
<7> [221.462531] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 wait for panel power cycle (464 ms remaining)
<7> [221.935644] 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> [221.946097] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [221.946472] 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> [221.946780] 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> [221.983182] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [221.983721] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [221.984140] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [221.984584] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [222.161667] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [222.162059] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [222.162407] 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> [222.164453] 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> [222.166020] 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> [222.166854] 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> [222.168014] 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> [222.169469] 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> [222.170594] 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> [222.170981] 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> [222.172493] 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> [222.172868] 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> [222.173686] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [222.174210] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [222.174654] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [222.175061] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 4518
<7> [222.176466] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [222.176805] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:272:plane 1C]
<7> [222.191097] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [222.192599] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [222.192978] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:388:pipe C]
<7> [224.727142] 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> [224.727661] 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> [224.930601] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [224.935120] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [224.945770] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:431] from all active usage due to RMFB ioctl
<7> [224.946265] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:272:plane 1C] because [FB:431] is removed
<7> [224.946330] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:272:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [224.946447] 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> [224.946529] 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> [224.946603] 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> [224.946673] 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> [224.946791] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:388:pipe C] data rate 0 num active planes 0
<7> [224.946912] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [224.947027] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [224.947147] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [224.947259] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [224.947368] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [224.948266] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [224.958182] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [224.958461] 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> [224.970371] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:272:plane 1C]
<7> [224.999296] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:392:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [224.999764] 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> [225.000139] 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> [225.000476] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [225.000840] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:391:DDI A/PHY A] [CRTC:150:pipe A]
<7> [225.001186] 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> [225.001494] 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> [225.001796] 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> [225.002111] 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> [225.002412] 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> [225.002711] 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> [225.003005] 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> [225.003314] 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> [225.003608] 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> [225.003903] 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> [225.004210] 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> [225.004505] 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> [225.004790] 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> [225.005071] 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> [225.005342] 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> [225.005615] 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> [225.005873] 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> [225.006138] 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> [225.006380] 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> [225.006615] 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> [225.006838] 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> [225.007068] 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> [225.007285] 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> [225.007493] 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> [225.007704] 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> [225.007903] 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> [225.008164] 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> [225.008366] 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> [225.008548] 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> [225.008727] 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> [225.008899] 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> [225.009080] 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> [225.009249] 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> [225.009417] 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> [225.009591] 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> [225.009756] 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> [225.009912] 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> [225.010074] 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> [225.010228] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [225.010370] 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> [225.010507] 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> [225.010645] 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> [225.010784] 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> [225.010923] 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> [225.011069] 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> [225.011208] 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> [225.011334] 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> [225.011462] 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> [225.011587] 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> [225.011704] 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> [225.011820] 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> [225.011929] 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> [225.012037] 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> [225.012156] 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> [225.012264] 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> [225.012367] 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> [225.012468] 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> [225.012570] 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> [225.012675] 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> [225.012781] 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> [225.012886] 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> [225.012991] 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> [225.013107] 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> [225.013214] 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> [225.013312] 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> [225.013413] 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> [225.013513] 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> [225.013609] 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> [225.013705] 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> [225.013799] 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> [225.013894] 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> [225.013990] 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> [225.014099] 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> [225.014206] 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> [225.014312] 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> [225.014420] 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> [225.014525] 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> [225.014629] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:388:pipe C] fastset requirement not met, forcing full modeset
<7> [225.014743] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:388:pipe C] releasing DPLL 0
<7> [225.014985] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [225.015109] 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> [225.015189] 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> [225.015269] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [225.015342] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [225.015418] 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> [225.015493] 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> [225.015572] 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> [225.015648] 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> [225.015732] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:382:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [225.015846] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 1390840 num active planes 1
<7> [225.015987] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [225.016220] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [225.016347] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [225.016455] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [225.016561] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [225.016671] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [225.016785] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:388:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [225.016990] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated DPLL 0
<7> [225.017137] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving DPLL 0
<7> [225.017271] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [225.017404] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [225.017515] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [225.017620] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [225.017725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [225.017830] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [225.017934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [225.018038] 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> [225.018150] 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> [225.018255] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [225.018358] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [225.018462] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [225.018566] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [225.018668] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [225.018773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [225.018876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [225.018980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [225.019087] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [225.019191] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [225.019295] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [225.019398] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [225.019501] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [225.019604] 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> [225.019708] 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> [225.019811] 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> [225.019915] 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> [225.020019] 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> [225.020133] 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> [225.020239] 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> [225.020342] 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> [225.020446] 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> [225.020550] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [225.020653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [225.020757] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [225.020861] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [225.020964] 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> [225.021074] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [225.021178] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [225.021281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [225.021384] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [225.021486] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [225.021589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [225.021692] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [225.021795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [225.021899] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [225.022002] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [225.022111] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [225.022218] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [225.022320] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.022423] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.022525] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.022629] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [225.022733] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [225.022836] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.022938] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.023041] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [225.023151] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [225.023255] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [225.023359] 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> [225.023468] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [225.023572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [225.023677] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [225.023782] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [225.023885] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [225.023989] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [225.024099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [225.024205] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:388:pipe C] enable: no [modeset]
<7> [225.024310] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:272:plane 1C] fb: [NOFB], visible: no
<7> [225.024415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:302:plane 2C] fb: [NOFB], visible: no
<7> [225.024521] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:332:plane 3C] fb: [NOFB], visible: no
<7> [225.024625] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:362:plane 4C] fb: [NOFB], visible: no
<7> [225.024728] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:372:plane 5C] fb: [NOFB], visible: no
<7> [225.024830] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:382:cursor C] fb: [NOFB], visible: no
<7> [225.025093] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [225.025303] 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> [225.026256] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [225.026450] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [225.231111] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 0
<7> [225.232132] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [225.237467] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power off
<7> [225.237920] 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> [225.238393] 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> [225.290584] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [225.291114] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - short
<7> [225.298092] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [225.298505] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:388:pipe C]
<7> [225.298535] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [225.300044] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [225.300522] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [225.301016] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:391:DDI A/PHY A]
<7> [225.301373] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:400:DDI TC1/PHY TC1]
<7> [225.301686] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST A]
<7> [225.301993] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST B]
<7> [225.302344] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST C]
<7> [225.302648] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:412:DDI TC2/PHY TC2]
<7> [225.302944] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST A]
<7> [225.303269] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST B]
<7> [225.303568] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST C]
<7> [225.303863] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:420:DDI TC3/PHY TC3]
<7> [225.304182] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST A]
<7> [225.304481] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST B]
<7> [225.304781] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST C]
<7> [225.305668] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [225.306201] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [225.306508] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [225.306858] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [225.308068] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [225.308433] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [225.310869] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turn panel power on
<7> [225.311187] 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> [225.329766] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [225.330193] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [225.330566] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [225.331000] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [225.807169] 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> [225.877606] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [225.878126] 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> [225.878507] 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> [225.915004] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [225.915520] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:391:DDI A/PHY A] - long
<7> [225.915891] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [225.916355] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:391:DDI A/PHY A]
<7> [226.085733] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [226.086461] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:391:DDI A/PHY A] PPS 0 turning VDD on
<7> [226.086951] 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> [226.090866] 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> [226.092602] 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> [226.093650] 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> [226.095044] 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> [226.096546] 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> [226.097858] 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> [226.098360] 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> [226.100024] 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> [226.100476] 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> [226.101431] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [226.102122] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [226.102599] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [226.103163] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:392:eDP-1] set backlight PWM = 4518
<7> [226.118929] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [226.121045] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:392:eDP-1]
<7> [226.121566] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [226.124746] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [226.125296] 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> [226.126479] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [226.126954] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:388:pipe C]
<7> [226.128134] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [226.129342] [IGT] kms_flip: exiting, ret=98
<7> [226.144997] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [226.145187] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [226.145312] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-02-13 19:13:13