Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-hdmi-a1-hdmi-a2

Result: Fail

git-log-oneline i915_display_info5 igt_runner5 runtimes5 results5.json results5-i915-load.json guc_logs5.tar boot5 dmesg5

DetailValue
Duration 3.71 seconds
Hostname 
shard-glk9
Igt-Version 
IGT-Version: 2.3-g82e6b9463 (x86_64) (Linux: 7.0.0-rc5-CI_DRM_18213-gcfe3d5f3eb5c+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1976 run_pair()
  #3 ../tests/kms_flip.c:2135 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (3.711s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:2293) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:2293) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.303802
last_received_ts = 133.303528
last_seq = 3163
current_ts = 133.320465
current_received_ts = 133.320297
current_seq = 3164
count = 16
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.320465
last_received_ts = 133.320297
last_seq = 3164
current_ts = 133.337128
current_received_ts = 133.336960
current_seq = 3165
count = 17
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.337128
last_received_ts = 133.336960
last_seq = 3165
current_ts = 133.353790
current_received_ts = 133.353516
current_seq = 3166
count = 18
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.353790
last_received_ts = 133.353516
last_seq = 3166
current_ts = 133.370468
current_received_ts = 133.370255
current_seq = 3167
count = 19
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.370468
last_received_ts = 133.370255
last_seq = 3167
current_ts = 133.387131
current_received_ts = 133.387070
current_seq = 3168
count = 20
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.387131
last_received_ts = 133.387070
last_seq = 3168
current_ts = 133.403809
current_received_ts = 133.403503
current_seq = 3169
count = 21
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.403809
last_received_ts = 133.403503
last_seq = 3169
current_ts = 133.420471
current_received_ts = 133.420303
current_seq = 3170
count = 22
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.420471
last_received_ts = 133.420303
last_seq = 3170
current_ts = 133.437134
current_received_ts = 133.436996
current_seq = 3171
count = 23
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.437134
last_received_ts = 133.436996
last_seq = 3171
current_ts = 133.453796
current_received_ts = 133.453537
current_seq = 3172
count = 24
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.453796
last_received_ts = 133.453537
last_seq = 3172
current_ts = 133.470459
current_received_ts = 133.470322
current_seq = 3173
count = 25
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.470459
last_received_ts = 133.470322
last_seq = 3173
current_ts = 133.487137
current_received_ts = 133.486908
current_seq = 3174
count = 26
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.487137
last_received_ts = 133.486908
last_seq = 3174
current_ts = 133.503799
current_received_ts = 133.503479
current_seq = 3175
count = 27
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.503799
last_received_ts = 133.503479
last_seq = 3175
current_ts = 133.520477
current_received_ts = 133.520279
current_seq = 3176
count = 28
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.520477
last_received_ts = 133.520279
last_seq = 3176
current_ts = 133.537140
current_received_ts = 133.537079
current_seq = 3177
count = 29
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.537140
last_received_ts = 133.537079
last_seq = 3177
current_ts = 133.553787
current_received_ts = 133.553543
current_seq = 3178
count = 30
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.553787
last_received_ts = 133.553543
last_seq = 3178
current_ts = 133.570465
current_received_ts = 133.570297
current_seq = 3179
count = 31
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.570465
last_received_ts = 133.570297
last_seq = 3179
current_ts = 133.587143
current_received_ts = 133.586838
current_seq = 3180
count = 32
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.587143
last_received_ts = 133.586838
last_seq = 3180
current_ts = 133.603806
current_received_ts = 133.603516
current_seq = 3181
count = 33
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.603806
last_received_ts = 133.603516
last_seq = 3181
current_ts = 133.620468
current_received_ts = 133.620316
current_seq = 3182
count = 34
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.620468
last_received_ts = 133.620316
last_seq = 3182
current_ts = 133.637131
current_received_ts = 133.637085
current_seq = 3183
count = 35
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.637131
last_received_ts = 133.637085
last_seq = 3183
current_ts = 133.653793
current_received_ts = 133.653503
current_seq = 3184
count = 36
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.653793
last_received_ts = 133.653503
last_seq = 3184
current_ts = 133.670456
current_received_ts = 133.670303
current_seq = 3185
count = 37
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.670456
last_received_ts = 133.670303
last_seq = 3185
current_ts = 133.687134
current_received_ts = 133.686981
current_seq = 3186
count = 38
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.687134
last_received_ts = 133.686981
last_seq = 3186
current_ts = 133.703796
current_received_ts = 133.703522
current_seq = 3187
count = 39
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.703796
last_received_ts = 133.703522
last_seq = 3187
current_ts = 133.720459
current_received_ts = 133.720245
current_seq = 3188
count = 40
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.720459
last_received_ts = 133.720245
last_seq = 3188
current_ts = 133.737137
current_received_ts = 133.737076
current_seq = 3189
count = 41
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.737137
last_received_ts = 133.737076
last_seq = 3189
current_ts = 133.753799
current_received_ts = 133.753525
current_seq = 3190
count = 42
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.753799
last_received_ts = 133.753525
last_seq = 3190
current_ts = 133.770462
current_received_ts = 133.770294
current_seq = 3191
count = 43
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.770462
last_received_ts = 133.770294
last_seq = 3191
current_ts = 133.787140
current_received_ts = 133.787064
current_seq = 3192
count = 44
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.787140
last_received_ts = 133.787064
last_seq = 3192
current_ts = 133.803802
current_received_ts = 133.803513
current_seq = 3193
count = 45
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.803802
last_received_ts = 133.803513
last_seq = 3193
current_ts = 133.820465
current_received_ts = 133.820221
current_seq = 3194
count = 46
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.820465
last_received_ts = 133.820221
last_seq = 3194
current_ts = 133.837128
current_received_ts = 133.836990
current_seq = 3195
count = 47
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 18us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.837128
last_received_ts = 133.836990
last_seq = 3195
current_ts = 133.853806
current_received_ts = 133.853485
current_seq = 3196
count = 48
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.853806
last_received_ts = 133.853485
last_seq = 3196
current_ts = 133.870468
current_received_ts = 133.870255
current_seq = 3197
count = 49
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.870468
last_received_ts = 133.870255
last_seq = 3197
current_ts = 133.887131
current_received_ts = 133.886993
current_seq = 3198
count = 50
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.887131
last_received_ts = 133.886993
last_seq = 3198
current_ts = 133.903809
current_received_ts = 133.903473
current_seq = 3199
count = 51
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.903809
last_received_ts = 133.903473
last_seq = 3199
current_ts = 133.920456
current_received_ts = 133.920319
current_seq = 3200
count = 52
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.920456
last_received_ts = 133.920319
last_seq = 3200
current_ts = 133.937134
current_received_ts = 133.936844
current_seq = 3201
count = 53
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 18us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.937134
last_received_ts = 133.936844
last_seq = 3201
current_ts = 133.953796
current_received_ts = 133.953522
current_seq = 3202
count = 54
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.953796
last_received_ts = 133.953522
last_seq = 3202
current_ts = 133.970474
current_received_ts = 133.970306
current_seq = 3203
count = 55
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.970474
last_received_ts = 133.970306
last_seq = 3203
current_ts = 133.987137
current_received_ts = 133.986816
current_seq = 3204
count = 56
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 26us
(kms_flip:2293) DEBUG: name = flip
last_ts = 133.987137
last_received_ts = 133.986816
last_seq = 3204
current_ts = 134.003799
current_received_ts = 134.003448
current_seq = 3205
count = 57
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.003799
last_received_ts = 134.003448
last_seq = 3205
current_ts = 134.020477
current_received_ts = 134.020309
current_seq = 3206
count = 58
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.020477
last_received_ts = 134.020309
last_seq = 3206
current_ts = 134.037125
current_received_ts = 134.036774
current_seq = 3207
count = 59
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.037125
last_received_ts = 134.036774
last_seq = 3207
current_ts = 134.053802
current_received_ts = 134.053452
current_seq = 3208
count = 60
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.053802
last_received_ts = 134.053452
last_seq = 3208
current_ts = 134.070465
current_received_ts = 134.070312
current_seq = 3209
count = 61
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.070465
last_received_ts = 134.070312
last_seq = 3209
current_ts = 134.087143
current_received_ts = 134.087097
current_seq = 3210
count = 62
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.087143
last_received_ts = 134.087097
last_seq = 3210
current_ts = 134.103806
current_received_ts = 134.103516
current_seq = 3211
count = 63
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.103806
last_received_ts = 134.103516
last_seq = 3211
current_ts = 134.120468
current_received_ts = 134.120300
current_seq = 3212
count = 64
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.120468
last_received_ts = 134.120300
last_seq = 3212
current_ts = 134.137131
current_received_ts = 134.136993
current_seq = 3213
count = 65
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.137131
last_received_ts = 134.136993
last_seq = 3213
current_ts = 134.153793
current_received_ts = 134.153519
current_seq = 3214
count = 66
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.153793
last_received_ts = 134.153519
last_seq = 3214
current_ts = 134.170471
current_received_ts = 134.170288
current_seq = 3215
count = 67
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.170471
last_received_ts = 134.170288
last_seq = 3215
current_ts = 134.187134
current_received_ts = 134.187088
current_seq = 3216
count = 68
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.187134
last_received_ts = 134.187088
last_seq = 3216
current_ts = 134.203796
current_received_ts = 134.203522
current_seq = 3217
count = 69
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.203796
last_received_ts = 134.203522
last_seq = 3217
current_ts = 134.220459
current_received_ts = 134.220306
current_seq = 3218
count = 70
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.220459
last_received_ts = 134.220306
last_seq = 3218
current_ts = 134.237137
current_received_ts = 134.236801
current_seq = 3219
count = 71
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.237137
last_received_ts = 134.236801
last_seq = 3219
current_ts = 134.253799
current_received_ts = 134.253525
current_seq = 3220
count = 72
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.253799
last_received_ts = 134.253525
last_seq = 3220
current_ts = 134.270477
current_received_ts = 134.270325
current_seq = 3221
count = 73
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.270477
last_received_ts = 134.270325
last_seq = 3221
current_ts = 134.287125
current_received_ts = 134.286911
current_seq = 3222
count = 74
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 26us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.287125
last_received_ts = 134.286911
last_seq = 3222
current_ts = 134.303802
current_received_ts = 134.303497
current_seq = 3223
count = 75
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.303802
last_received_ts = 134.303497
last_seq = 3223
current_ts = 134.320465
current_received_ts = 134.320343
current_seq = 3224
count = 76
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.320465
last_received_ts = 134.320343
last_seq = 3224
current_ts = 134.337143
current_received_ts = 134.336914
current_seq = 3225
count = 77
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.337143
last_received_ts = 134.336914
last_seq = 3225
current_ts = 134.353806
current_received_ts = 134.353500
current_seq = 3226
count = 78
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.353806
last_received_ts = 134.353500
last_seq = 3226
current_ts = 134.370468
current_received_ts = 134.370270
current_seq = 3227
count = 79
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.370468
last_received_ts = 134.370270
last_seq = 3227
current_ts = 134.387146
current_received_ts = 134.387100
current_seq = 3228
count = 80
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.387146
last_received_ts = 134.387100
last_seq = 3228
current_ts = 134.403809
current_received_ts = 134.403564
current_seq = 3229
count = 81
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.403809
last_received_ts = 134.403564
last_seq = 3229
current_ts = 134.420471
current_received_ts = 134.420273
current_seq = 3230
count = 82
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.420471
last_received_ts = 134.420273
last_seq = 3230
current_ts = 134.437134
current_received_ts = 134.437103
current_seq = 3231
count = 83
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.437134
last_received_ts = 134.437103
last_seq = 3231
current_ts = 134.453796
current_received_ts = 134.453552
current_seq = 3232
count = 84
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.453796
last_received_ts = 134.453552
last_seq = 3232
current_ts = 134.470474
current_received_ts = 134.470306
current_seq = 3233
count = 85
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.470474
last_received_ts = 134.470306
last_seq = 3233
current_ts = 134.487122
current_received_ts = 134.486908
current_seq = 3234
count = 86
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.487122
last_received_ts = 134.486908
last_seq = 3234
current_ts = 134.503799
current_received_ts = 134.503555
current_seq = 3235
count = 87
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.503799
last_received_ts = 134.503555
last_seq = 3235
current_ts = 134.520462
current_received_ts = 134.520248
current_seq = 3236
count = 88
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.520462
last_received_ts = 134.520248
last_seq = 3236
current_ts = 134.537140
current_received_ts = 134.536835
current_seq = 3237
count = 89
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.537140
last_received_ts = 134.536835
last_seq = 3237
current_ts = 134.553802
current_received_ts = 134.553452
current_seq = 3238
count = 90
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.553802
last_received_ts = 134.553452
last_seq = 3238
current_ts = 134.570480
current_received_ts = 134.570328
current_seq = 3239
count = 91
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.570480
last_received_ts = 134.570328
last_seq = 3239
current_ts = 134.587143
current_received_ts = 134.586914
current_seq = 3240
count = 92
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.587143
last_received_ts = 134.586914
last_seq = 3240
current_ts = 134.603790
current_received_ts = 134.603470
current_seq = 3241
count = 93
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.603790
last_received_ts = 134.603470
last_seq = 3241
current_ts = 134.620483
current_received_ts = 134.620316
current_seq = 3242
count = 94
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.620483
last_received_ts = 134.620316
last_seq = 3242
current_ts = 134.637131
current_received_ts = 134.636902
current_seq = 3243
count = 95
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.637131
last_received_ts = 134.636902
last_seq = 3243
current_ts = 134.653809
current_received_ts = 134.653549
current_seq = 3244
count = 96
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.653809
last_received_ts = 134.653549
last_seq = 3244
current_ts = 134.670471
current_received_ts = 134.670242
current_seq = 3245
count = 97
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.670471
last_received_ts = 134.670242
last_seq = 3245
current_ts = 134.687134
current_received_ts = 134.687119
current_seq = 3246
count = 98
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.687134
last_received_ts = 134.687119
last_seq = 3246
current_ts = 134.703812
current_received_ts = 134.703552
current_seq = 3247
count = 99
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.703812
last_received_ts = 134.703552
last_seq = 3247
current_ts = 134.720459
current_received_ts = 134.720230
current_seq = 3248
count = 100
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 26us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.720459
last_received_ts = 134.720230
last_seq = 3248
current_ts = 134.737137
current_received_ts = 134.736969
current_seq = 3249
count = 101
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.737137
last_received_ts = 134.736969
last_seq = 3249
current_ts = 134.753799
current_received_ts = 134.753510
current_seq = 3250
count = 102
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.753799
last_received_ts = 134.753510
last_seq = 3250
current_ts = 134.770462
current_received_ts = 134.770325
current_seq = 3251
count = 103
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.770462
last_received_ts = 134.770325
last_seq = 3251
current_ts = 134.787140
current_received_ts = 134.786926
current_seq = 3252
count = 104
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 102us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.787140
last_received_ts = 134.786926
last_seq = 3252
current_ts = 134.803802
current_received_ts = 134.803528
current_seq = 3253
count = 105
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.803802
last_received_ts = 134.803528
last_seq = 3253
current_ts = 134.820465
current_received_ts = 134.820328
current_seq = 3254
count = 106
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.820465
last_received_ts = 134.820328
last_seq = 3254
current_ts = 134.837128
current_received_ts = 134.836899
current_seq = 3255
count = 107
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.837128
last_received_ts = 134.836899
last_seq = 3255
current_ts = 134.853806
current_received_ts = 134.853500
current_seq = 3256
count = 108
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.853806
last_received_ts = 134.853500
last_seq = 3256
current_ts = 134.870468
current_received_ts = 134.870255
current_seq = 3257
count = 109
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.870468
last_received_ts = 134.870255
last_seq = 3257
current_ts = 134.887131
current_received_ts = 134.886658
current_seq = 3258
count = 110
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.887131
last_received_ts = 134.886658
last_seq = 3258
current_ts = 134.903809
current_received_ts = 134.903534
current_seq = 3259
count = 111
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.903809
last_received_ts = 134.903534
last_seq = 3259
current_ts = 134.920471
current_received_ts = 134.920303
current_seq = 3260
count = 112
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.920471
last_received_ts = 134.920303
last_seq = 3260
current_ts = 134.937149
current_received_ts = 134.936905
current_seq = 3261
count = 113
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.937149
last_received_ts = 134.936905
last_seq = 3261
current_ts = 134.953796
current_received_ts = 134.953522
current_seq = 3262
count = 114
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.953796
last_received_ts = 134.953522
last_seq = 3262
current_ts = 134.970474
current_received_ts = 134.970306
current_seq = 3263
count = 115
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.970474
last_received_ts = 134.970306
last_seq = 3263
current_ts = 134.987137
current_received_ts = 134.986893
current_seq = 3264
count = 116
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 134.987137
last_received_ts = 134.986893
last_seq = 3264
current_ts = 135.003815
current_received_ts = 135.003510
current_seq = 3265
count = 117
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.003815
last_received_ts = 135.003510
last_seq = 3265
current_ts = 135.020462
current_received_ts = 135.020264
current_seq = 3266
count = 118
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.020462
last_received_ts = 135.020264
last_seq = 3266
current_ts = 135.037140
current_received_ts = 135.037003
current_seq = 3267
count = 119
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.037140
last_received_ts = 135.037003
last_seq = 3267
current_ts = 135.053802
current_received_ts = 135.053513
current_seq = 3268
count = 120
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.053802
last_received_ts = 135.053513
last_seq = 3268
current_ts = 135.070465
current_received_ts = 135.070282
current_seq = 3269
count = 121
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.070465
last_received_ts = 135.070282
last_seq = 3269
current_ts = 135.087143
current_received_ts = 135.086899
current_seq = 3270
count = 122
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 25us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.087143
last_received_ts = 135.086899
last_seq = 3270
current_ts = 135.103790
current_received_ts = 135.103500
current_seq = 3271
count = 123
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.103790
last_received_ts = 135.103500
last_seq = 3271
current_ts = 135.120468
current_received_ts = 135.120560
current_seq = 3272
count = 124
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.120468
last_received_ts = 135.120560
last_seq = 3272
current_ts = 135.137131
current_received_ts = 135.136749
current_seq = 3273
count = 125
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 14us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.137131
last_received_ts = 135.136749
last_seq = 3273
current_ts = 135.153809
current_received_ts = 135.153534
current_seq = 3274
count = 126
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 19us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.153809
last_received_ts = 135.153534
last_seq = 3274
current_ts = 135.170471
current_received_ts = 135.170380
current_seq = 3275
count = 127
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 22us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.170471
last_received_ts = 135.170380
last_seq = 3275
current_ts = 135.187134
current_received_ts = 135.187119
current_seq = 3276
count = 128
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.187134
last_received_ts = 135.187119
last_seq = 3276
current_ts = 135.203796
current_received_ts = 135.203522
current_seq = 3277
count = 129
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 19us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.203796
last_received_ts = 135.203522
last_seq = 3277
current_ts = 135.220474
current_received_ts = 135.220306
current_seq = 3278
count = 130
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.220474
last_received_ts = 135.220306
last_seq = 3278
current_ts = 135.237137
current_received_ts = 135.237045
current_seq = 3279
count = 131
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 23us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.237137
last_received_ts = 135.237045
last_seq = 3279
current_ts = 135.253799
current_received_ts = 135.253510
current_seq = 3280
count = 132
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 21us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.253799
last_received_ts = 135.253510
last_seq = 3280
current_ts = 135.270477
current_received_ts = 135.270309
current_seq = 3281
count = 133
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 24us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.270477
last_received_ts = 135.270309
last_seq = 3281
current_ts = 135.287140
current_received_ts = 135.286896
current_seq = 3282
count = 134
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 26us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.287140
last_received_ts = 135.286896
last_seq = 3282
current_ts = 135.303802
current_received_ts = 135.303513
current_seq = 3283
count = 135
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 20us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.303802
last_received_ts = 135.303513
last_seq = 3283
current_ts = 135.320480
current_received_ts = 135.320297
current_seq = 3284
count = 136
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 19us
(kms_flip:2293) DEBUG: name = flip
last_ts = 135.320480
last_received_ts = 135.320297
last_seq = 3284
current_ts = 135.337143
current_received_ts = 135.336929
current_seq = 3285
count = 137
seq_step = 1
(kms_flip:2293) DEBUG: Vblank took 1118us
(kms_flip:2293) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:2293) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2293) igt_core-INFO: Stack trace:
(kms_flip:2293) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2293) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:2293) igt_core-INFO:   #2 ../tests/kms_flip.c:1976 run_pair()
(kms_flip:2293) igt_core-INFO:   #3 ../tests/kms_flip.c:2135 __igt_unique____real_main2047()
(kms_flip:2293) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:2293) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2293) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2293) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (3.711s)
Dmesg
<6> [132.451699] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [132.456100] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:179]
<7> [132.456435] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:185]
<7> [132.526485] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [132.526930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [132.527923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [132.528935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [132.529481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [132.529857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [132.530349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [132.530727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [132.531136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [132.531622] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [132.532038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [132.532414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [132.532790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [132.533207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [132.533583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [132.533958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [132.534398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [132.534799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [132.535231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [132.535607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [132.536011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [132.536416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [132.536791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [132.537191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [132.537567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [132.537943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [132.538343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [132.538776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [132.539192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [132.539571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [132.539947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [132.540351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [132.540729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [132.541244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [132.541628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [132.542031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [132.542409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [132.542813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [132.543240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [132.543639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [132.544054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [132.544429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [132.544803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [132.545237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [132.545615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.546059] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [132.546065] i915 0000:00:02.0: colorspace: RGB
<7> [132.546069] i915 0000:00:02.0: scan mode: Underscan
<7> [132.546072] i915 0000:00:02.0: colorimetry: No Data
<7> [132.546076] i915 0000:00:02.0: picture aspect: No Data
<7> [132.546079] i915 0000:00:02.0: active aspect: Same as Picture
<7> [132.546082] i915 0000:00:02.0: itc: No Data
<7> [132.546086] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [132.546089] i915 0000:00:02.0: quantization range: Full
<7> [132.546093] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [132.546096] i915 0000:00:02.0: video code: 0
<7> [132.546100] i915 0000:00:02.0: ycc quantization range: Full
<7> [132.546103] i915 0000:00:02.0: hdmi content type: Graphics
<7> [132.546106] i915 0000:00:02.0: pixel repeat: 0
<7> [132.546110] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [132.546113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.546491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [132.546867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.547268] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [132.547272] i915 0000:00:02.0: vendor: Intel
<7> [132.547276] i915 0000:00:02.0: product: Integrated gfx
<7> [132.547279] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [132.547284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.547660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [132.548151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.548529] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [132.548534] i915 0000:00:02.0: empty frame
<7> [132.548537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.548998] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [132.549385] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [132.549822] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [132.550276] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [132.550726] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [132.551156] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [132.551638] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [132.552072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [132.552453] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [132.552831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [132.553273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [132.553650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [132.554076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [132.554598] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:78:pipe A]
<7> [132.579645] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [132.580733] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [132.598038] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [132.699438] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [132.700437] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [132.702742] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [132.703347] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [132.703772] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [132.704516] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [132.710591] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [132.711372] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [132.713678] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [132.713863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [132.714726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [132.715381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [132.715785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [132.716348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [132.716731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [132.717286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [132.717665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [132.719053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [132.719460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [132.719839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [132.720607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [132.721232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [132.721623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [132.722207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [132.722587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [132.722963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [132.723574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [132.723956] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [132.725397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [132.725817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [132.726693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [132.727377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [132.727759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [132.728325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [132.728705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [132.729305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [132.729686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [132.730937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [132.731763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [132.732419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [132.732805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [132.733426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [132.733810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [132.734372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [132.734755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [132.736080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [132.736492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [132.736868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [132.737709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [132.738368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [132.738760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [132.739353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [132.739736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.740293] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [132.740299] i915 0000:00:02.0: colorspace: RGB
<7> [132.740303] i915 0000:00:02.0: scan mode: Underscan
<7> [132.740307] i915 0000:00:02.0: colorimetry: No Data
<7> [132.740310] i915 0000:00:02.0: picture aspect: No Data
<7> [132.740314] i915 0000:00:02.0: active aspect: Same as Picture
<7> [132.740317] i915 0000:00:02.0: itc: No Data
<7> [132.740320] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [132.740324] i915 0000:00:02.0: quantization range: Full
<7> [132.740327] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [132.740331] i915 0000:00:02.0: video code: 0
<7> [132.740334] i915 0000:00:02.0: ycc quantization range: Full
<7> [132.740337] i915 0000:00:02.0: hdmi content type: Graphics
<7> [132.740340] i915 0000:00:02.0: pixel repeat: 0
<7> [132.740344] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [132.740348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.740729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [132.742733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.743610] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [132.743615] i915 0000:00:02.0: vendor: Intel
<7> [132.743618] i915 0000:00:02.0: product: Integrated gfx
<7> [132.743622] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [132.743626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.744332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [132.744721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.745279] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [132.745284] i915 0000:00:02.0: empty frame
<7> [132.745288] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.745670] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [132.746284] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [132.746722] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [132.748104] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [132.748591] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [132.749502] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [132.749893] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [132.750607] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [132.751300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [132.751695] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [132.752256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [132.752637] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [132.753208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [132.753590] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [132.755214] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Disable audio codec on [CRTC:121:pipe B]
<7> [132.777093] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [132.777664] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [132.795030] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [132.899356] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [132.900609] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [132.902839] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [132.903748] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4
<7> [132.904462] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [132.904848] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [132.905442] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [132.906290] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [132.906719] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [132.908526] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [132.910299] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [132.910763] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [132.910825] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [132.911351] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [132.911855] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [132.912507] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [132.912890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [132.914245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [132.914658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [132.915385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [132.915772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [132.916405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [132.916788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [132.917425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [132.917811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [132.918401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [132.918782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [132.920181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [132.920593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [132.921452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [132.921845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [132.922505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [132.922895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [132.923453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [132.923834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [132.924427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [132.924811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [132.926398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [132.926811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [132.927677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [132.928150] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [132.928899] i915 0000:00:02.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [132.929381] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [132.930763] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [132.932154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [132.932623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [132.933232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [132.933628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [132.934175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [132.934557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [132.934935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [132.936569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [132.937220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [132.937606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [132.938341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [132.938869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [132.939445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [132.939830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [132.940433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [132.940815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [132.942272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [132.942693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [132.943575] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [132.944242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.944636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.945216] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [132.945222] i915 0000:00:02.0: colorspace: RGB
<7> [132.945226] i915 0000:00:02.0: scan mode: Underscan
<7> [132.945230] i915 0000:00:02.0: colorimetry: No Data
<7> [132.945234] i915 0000:00:02.0: picture aspect: No Data
<7> [132.945237] i915 0000:00:02.0: active aspect: Same as Picture
<7> [132.945240] i915 0000:00:02.0: itc: No Data
<7> [132.945244] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [132.945248] i915 0000:00:02.0: quantization range: Full
<7> [132.945251] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [132.945254] i915 0000:00:02.0: video code: 0
<7> [132.945258] i915 0000:00:02.0: ycc quantization range: Full
<7> [132.945261] i915 0000:00:02.0: hdmi content type: Graphics
<7> [132.945264] i915 0000:00:02.0: pixel repeat: 0
<7> [132.945267] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [132.945271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [132.945651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.946317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.946697] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [132.946701] i915 0000:00:02.0: vendor: Intel
<7> [132.946707] i915 0000:00:02.0: product: Integrated gfx
<7> [132.946710] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [132.946714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [132.948930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [132.949603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [132.950150] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [132.950157] i915 0000:00:02.0: empty frame
<7> [132.950163] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [132.950624] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [132.951509] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [132.951900] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [132.952658] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122: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> [132.953214] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [132.953590] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [132.953959] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [132.954549] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [132.956843] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [132.957420] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [132.957858] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [132.958621] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [132.959264] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [132.959655] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [132.960285] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [132.960680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [132.961249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [132.961638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [132.963184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [132.963598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [132.964447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [132.964832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [132.965477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [132.965863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [132.966426] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [132.966432] i915 0000:00:02.0: colorspace: RGB
<7> [132.966436] i915 0000:00:02.0: scan mode: Underscan
<7> [132.966439] i915 0000:00:02.0: colorimetry: No Data
<7> [132.966443] i915 0000:00:02.0: picture aspect: No Data
<7> [132.966447] i915 0000:00:02.0: active aspect: Same as Picture
<7> [132.966450] i915 0000:00:02.0: itc: No Data
<7> [132.966453] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [132.966457] i915 0000:00:02.0: quantization range: Full
<7> [132.966460] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [132.966463] i915 0000:00:02.0: video code: 0
<7> [132.966466] i915 0000:00:02.0: ycc quantization range: Full
<7> [132.966470] i915 0000:00:02.0: hdmi content type: Graphics
<7> [132.966473] i915 0000:00:02.0: pixel repeat: 0
<7> [132.966476] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [132.966480] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [132.966483] i915 0000:00:02.0: vendor: Intel
<7> [132.966487] i915 0000:00:02.0: product: Integrated gfx
<7> [132.966490] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [132.966494] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [132.966498] i915 0000:00:02.0: empty frame
<7> [132.966501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [132.966882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [132.967598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [132.969772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [132.970515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [132.970902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [132.971547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [132.971939] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [132.972492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [132.972872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [132.973511] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [132.973890] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [132.975207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [132.975624] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [132.976269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [132.976650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [132.977262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [132.977654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [132.978258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [132.978639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [132.979847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [132.980445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [132.980827] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [132.981400] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [132.981778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [132.982342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [132.982723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [132.983313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [132.983693] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [132.985079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [132.985484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [132.985860] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [132.986520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [132.988504] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [132.989031] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [132.989918] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [132.991850] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [132.992544] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [132.993399] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13
<7> [132.993844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [132.994427] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [132.995152] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [132.995551] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [132.996927] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [132.997475] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [133.015688] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD
<7> [133.028488] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [133.032394] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [133.032873] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [133.033683] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [133.034410] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [133.037616] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [133.037682] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [133.037874] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [133.038906] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [133.039564] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [133.039942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [133.040671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [133.041222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [133.041603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [133.042208] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [133.042224] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [133.043240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [133.043643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [133.044363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [133.044746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [133.045446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [133.045840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [133.046451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [133.046832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [133.047444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [133.047826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [133.049215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [133.049637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [133.050395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [133.050779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [133.051421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [133.051812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [133.052438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [133.052820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [133.053410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [133.053791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [133.055102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [133.055499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [133.055877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [133.056683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [133.057377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [133.057759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [133.058311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [133.058692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [133.059265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [133.059645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [133.061114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [133.061514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [133.061891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [133.062665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [133.063303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [133.063698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [133.064278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [133.064658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [133.065264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [133.065648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [133.066199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [133.066577] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [133.066581] i915 0000:00:02.0: colorspace: RGB
<7> [133.066585] i915 0000:00:02.0: scan mode: Underscan
<7> [133.066589] i915 0000:00:02.0: colorimetry: No Data
<7> [133.066592] i915 0000:00:02.0: picture aspect: No Data
<7> [133.066595] i915 0000:00:02.0: active aspect: Same as Picture
<7> [133.066599] i915 0000:00:02.0: itc: No Data
<7> [133.066602] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [133.066605] i915 0000:00:02.0: quantization range: Full
<7> [133.066608] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [133.066611] i915 0000:00:02.0: video code: 0
<7> [133.066615] i915 0000:00:02.0: ycc quantization range: Full
<7> [133.066618] i915 0000:00:02.0: hdmi content type: Graphics
<7> [133.066621] i915 0000:00:02.0: pixel repeat: 0
<7> [133.066624] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [133.066628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [133.068856] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [133.069639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [133.070283] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [133.070288] i915 0000:00:02.0: vendor: Intel
<7> [133.070292] i915 0000:00:02.0: product: Integrated gfx
<7> [133.070295] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [133.070300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [133.070680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [133.071231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [133.071609] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [133.071613] i915 0000:00:02.0: empty frame
<7> [133.071619] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [133.072250] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [133.072652] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [133.074281] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [133.074676] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [133.075386] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36: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> [133.075766] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [133.076438] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [133.076815] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [133.077419] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [133.077796] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [133.078370] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [133.078826] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [133.080425] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [133.080837] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [133.081625] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [133.082284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [133.082680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [133.083247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [133.083626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [133.084238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [133.084619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [133.085837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [133.086552] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [133.086933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [133.087550] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [133.087555] i915 0000:00:02.0: colorspace: RGB
<7> [133.087559] i915 0000:00:02.0: scan mode: Underscan
<7> [133.087562] i915 0000:00:02.0: colorimetry: No Data
<7> [133.087566] i915 0000:00:02.0: picture aspect: No Data
<7> [133.087569] i915 0000:00:02.0: active aspect: Same as Picture
<7> [133.087572] i915 0000:00:02.0: itc: No Data
<7> [133.087576] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [133.087579] i915 0000:00:02.0: quantization range: Full
<7> [133.087582] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [133.087586] i915 0000:00:02.0: video code: 0
<7> [133.087589] i915 0000:00:02.0: ycc quantization range: Full
<7> [133.087592] i915 0000:00:02.0: hdmi content type: Graphics
<7> [133.087595] i915 0000:00:02.0: pixel repeat: 0
<7> [133.087598] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [133.087602] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [133.087606] i915 0000:00:02.0: vendor: Intel
<7> [133.087609] i915 0000:00:02.0: product: Integrated gfx
<7> [133.087612] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [133.087616] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [133.087619] i915 0000:00:02.0: empty frame
<7> [133.087623] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [133.088288] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [133.088668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [133.089260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [133.089638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [133.091697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [133.092278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [133.092657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [133.093410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [133.093792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [133.094417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [133.094799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [133.095347] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [133.095726] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [133.096332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [133.096711] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [133.098047] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [133.098447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [133.098826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [133.099646] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [133.100271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [133.100653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [133.101232] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [133.101611] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [133.102083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [133.102460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [133.102834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [133.104206] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [133.104600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [133.105381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [133.105766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [133.106387] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [133.106766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [133.107630] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [133.108531] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [133.108913] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [133.115715] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [133.116203] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [133.116772] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [133.117806] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [133.119441] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [133.119834] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [133.136182] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:78:pipe A], 32 bytes ELD
<7> [133.152827] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [133.153273] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [133.153814] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [133.155171] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<6> [136.162723] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-03-26 03:44:29