Results for igt@kms_flip@flip-vs-blocking-wf-vblank@b-hdmi-a2

Machine description: shard-rkl-3

Result: Fail

git-log-oneline i915_display_info11 igt_runner11 runtimes11 results11.json boot11 dmesg11

DetailValue
Duration 18.62 seconds
Hostname
shard-rkl-3
Igt-Version
IGT-Version: 1.29-g6b9242949 (x86_64) (Linux: 6.13.0-rc1-CI_DRM_15799-ge57b4b7cd137+ x86_64)
Out
Starting dynamic subtest: B-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19066.1us +- 6.115us accuracy 0.10%
vblank interval differs from modeline! expected 18973.8us, measured 19066us +- 6.115us, difference 92.3us (15.1 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A2: FAIL (18.624s)
Subtest flip-vs-blocking-wf-vblank: FAIL (25.369s)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2246 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2930 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2386 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err
Starting dynamic subtest: B-HDMI-A2
(kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2073) DEBUG: name = flip
last_ts = 79.339211
last_received_ts = 79.509750
last_seq = 204
current_ts = 79.529495
current_received_ts = 79.700043
current_seq = 214
count = 19
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 79.339211/204, current 79.529495/214: elapsed=190284.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 79.510468
last_received_ts = 79.509727
last_seq = 213
current_ts = 79.700752
current_received_ts = 79.700012
current_seq = 223
count = 19
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 79.510468/213, current 79.700752/223: elapsed=190286.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 79.529495
last_received_ts = 79.700043
last_seq = 214
current_ts = 79.719788
current_received_ts = 79.890327
current_seq = 224
count = 20
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 79.529495/214, current 79.719788/224: elapsed=190287.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 79.700752
last_received_ts = 79.700012
last_seq = 223
current_ts = 79.891045
current_received_ts = 79.890305
current_seq = 233
count = 20
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 79.700752/223, current 79.891045/233: elapsed=190285.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 79.719788
last_received_ts = 79.890327
last_seq = 224
current_ts = 79.910065
current_received_ts = 80.080605
current_seq = 234
count = 21
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 79.719788/224, current 79.910065/234: elapsed=190283.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 79.891045
last_received_ts = 79.890305
last_seq = 233
current_ts = 80.081322
current_received_ts = 80.080582
current_seq = 243
count = 21
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 79.891045/233, current 80.081322/243: elapsed=190283.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 79.910065
last_received_ts = 80.080605
last_seq = 234
current_ts = 80.100357
current_received_ts = 80.270828
current_seq = 244
count = 22
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 79.910065/234, current 80.100357/244: elapsed=190287.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 80.081322
last_received_ts = 80.080582
last_seq = 243
current_ts = 80.271545
current_received_ts = 80.270813
current_seq = 253
count = 22
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 80.081322/243, current 80.271545/253: elapsed=190225.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 80.100357
last_received_ts = 80.270828
last_seq = 244
current_ts = 80.290573
current_received_ts = 80.461090
current_seq = 254
count = 23
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 80.100357/244, current 80.290573/254: elapsed=190218.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 80.271545
last_received_ts = 80.270813
last_seq = 253
current_ts = 80.461769
current_received_ts = 80.461044
current_seq = 263
count = 23
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 80.271545/253, current 80.461769/263: elapsed=190221.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 80.290573
last_received_ts = 80.461090
last_seq = 254
current_ts = 80.480782
current_received_ts = 80.651283
current_seq = 264
count = 24
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 80.290573/254, current 80.480782/264: elapsed=190207.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 80.461769
last_received_ts = 80.461044
last_seq = 263
current_ts = 80.651985
current_received_ts = 80.651253
current_seq = 273
count = 24
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 80.461769/263, current 80.651985/273: elapsed=190216.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 80.480782
last_received_ts = 80.651283
last_seq = 264
current_ts = 80.670998
current_received_ts = 80.841499
current_seq = 274
count = 25
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 80.480782/264, current 80.670998/274: elapsed=190218.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 80.651985
last_received_ts = 80.651253
last_seq = 273
current_ts = 80.842201
current_received_ts = 80.841469
current_seq = 283
count = 25
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 80.651985/273, current 80.842201/283: elapsed=190217.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 80.670998
last_received_ts = 80.841499
last_seq = 274
current_ts = 80.861206
current_received_ts = 81.031715
current_seq = 284
count = 26
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 80.670998/274, current 80.861206/284: elapsed=190212.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 80.842201
last_received_ts = 80.841469
last_seq = 283
current_ts = 81.032417
current_received_ts = 81.031685
current_seq = 293
count = 26
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 80.842201/283, current 81.032417/293: elapsed=190215.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = flip
last_ts = 80.861206
last_received_ts = 81.031715
last_seq = 284
current_ts = 81.051430
current_received_ts = 81.221901
current_seq = 294
count = 27
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 80.861206/284, current 81.051430/294: elapsed=190221.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.032417
last_received_ts = 81.031685
last_seq = 293
current_ts = 81.222595
current_received_ts = 81.221878
current_seq = 303
count = 27
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.032417/293, current 81.222595/303: elapsed=190181.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.051430
last_received_ts = 81.221901
last_seq = 294
current_ts = 81.241615
current_received_ts = 81.412064
current_seq = 304
count = 28
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.051430/294, current 81.241615/304: elapsed=190186.0us expected=190660.6us +- 953.3us, error 0.2%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.222595
last_received_ts = 81.221878
last_seq = 303
current_ts = 81.412743
current_received_ts = 81.412033
current_seq = 313
count = 28
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.222595/303, current 81.412743/313: elapsed=190141.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.241615
last_received_ts = 81.412064
last_seq = 304
current_ts = 81.431755
current_received_ts = 81.602211
current_seq = 314
count = 29
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.241615/304, current 81.431755/314: elapsed=190140.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.412743
last_received_ts = 81.412033
last_seq = 313
current_ts = 81.602898
current_received_ts = 81.602180
current_seq = 323
count = 29
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.412743/313, current 81.602898/323: elapsed=190156.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.431755
last_received_ts = 81.602211
last_seq = 314
current_ts = 81.621925
current_received_ts = 81.792366
current_seq = 324
count = 30
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.431755/314, current 81.621925/324: elapsed=190172.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.602898
last_received_ts = 81.602180
last_seq = 323
current_ts = 81.793068
current_received_ts = 81.792336
current_seq = 333
count = 30
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.602898/323, current 81.793068/333: elapsed=190173.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.621925
last_received_ts = 81.792366
last_seq = 324
current_ts = 81.812073
current_received_ts = 81.982529
current_seq = 334
count = 31
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.621925/324, current 81.812073/334: elapsed=190142.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.793068
last_received_ts = 81.792336
last_seq = 333
current_ts = 81.983208
current_received_ts = 81.982498
current_seq = 343
count = 31
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.793068/333, current 81.983208/343: elapsed=190140.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 81.812073
last_received_ts = 81.982529
last_seq = 334
current_ts = 82.002228
current_received_ts = 82.172668
current_seq = 344
count = 32
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 81.812073/334, current 82.002228/344: elapsed=190155.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 81.983208
last_received_ts = 81.982498
last_seq = 343
current_ts = 82.173347
current_received_ts = 82.172638
current_seq = 353
count = 32
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 81.983208/343, current 82.173347/353: elapsed=190139.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.002228
last_received_ts = 82.172668
last_seq = 344
current_ts = 82.192360
current_received_ts = 82.362770
current_seq = 354
count = 33
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.002228/344, current 82.192360/354: elapsed=190133.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 82.173347
last_received_ts = 82.172638
last_seq = 353
current_ts = 82.363457
current_received_ts = 82.362747
current_seq = 363
count = 33
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 82.173347/353, current 82.363457/363: elapsed=190105.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.192360
last_received_ts = 82.362770
last_seq = 354
current_ts = 82.382477
current_received_ts = 82.552872
current_seq = 364
count = 34
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.192360/354, current 82.382477/364: elapsed=190120.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 82.363457
last_received_ts = 82.362747
last_seq = 363
current_ts = 82.553558
current_received_ts = 82.552841
current_seq = 373
count = 34
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 82.363457/363, current 82.553558/373: elapsed=190103.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.382477
last_received_ts = 82.552872
last_seq = 364
current_ts = 82.572563
current_received_ts = 82.742966
current_seq = 374
count = 35
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.382477/364, current 82.572563/374: elapsed=190088.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 82.553558
last_received_ts = 82.552841
last_seq = 373
current_ts = 82.743675
current_received_ts = 82.742943
current_seq = 383
count = 35
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 82.553558/373, current 82.743675/383: elapsed=190119.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.572563
last_received_ts = 82.742966
last_seq = 374
current_ts = 82.762672
current_received_ts = 82.933083
current_seq = 384
count = 36
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.572563/374, current 82.762672/384: elapsed=190104.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 82.743675
last_received_ts = 82.742943
last_seq = 383
current_ts = 82.933762
current_received_ts = 82.933052
current_seq = 393
count = 36
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 82.743675/383, current 82.933762/393: elapsed=190089.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.762672
last_received_ts = 82.933083
last_seq = 384
current_ts = 82.952774
current_received_ts = 83.123177
current_seq = 394
count = 37
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.762672/384, current 82.952774/394: elapsed=190105.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 82.933762
last_received_ts = 82.933052
last_seq = 393
current_ts = 83.123863
current_received_ts = 83.123146
current_seq = 403
count = 37
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 82.933762/393, current 83.123863/403: elapsed=190099.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 82.952774
last_received_ts = 83.123177
last_seq = 394
current_ts = 83.142883
current_received_ts = 83.313232
current_seq = 404
count = 38
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 82.952774/394, current 83.142883/404: elapsed=190110.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 83.123863
last_received_ts = 83.123146
last_seq = 403
current_ts = 83.313919
current_received_ts = 83.313210
current_seq = 413
count = 38
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 83.123863/403, current 83.313919/413: elapsed=190057.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.142883
last_received_ts = 83.313232
last_seq = 404
current_ts = 83.332947
current_received_ts = 83.503288
current_seq = 414
count = 39
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.142883/404, current 83.332947/414: elapsed=190058.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 83.313919
last_received_ts = 83.313210
last_seq = 413
current_ts = 83.503983
current_received_ts = 83.503265
current_seq = 423
count = 39
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 83.313919/413, current 83.503983/423: elapsed=190060.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.332947
last_received_ts = 83.503288
last_seq = 414
current_ts = 83.522987
current_received_ts = 83.693344
current_seq = 424
count = 40
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.332947/414, current 83.522987/424: elapsed=190046.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 83.503983
last_received_ts = 83.503265
last_seq = 423
current_ts = 83.694054
current_received_ts = 83.693321
current_seq = 433
count = 40
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 83.503983/423, current 83.694054/433: elapsed=190073.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.522987
last_received_ts = 83.693344
last_seq = 424
current_ts = 83.713043
current_received_ts = 83.883415
current_seq = 434
count = 41
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.522987/424, current 83.713043/434: elapsed=190054.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 83.694054
last_received_ts = 83.693321
last_seq = 433
current_ts = 83.884094
current_received_ts = 83.883392
current_seq = 443
count = 41
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 83.694054/433, current 83.884094/443: elapsed=190045.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.713043
last_received_ts = 83.883415
last_seq = 434
current_ts = 83.903099
current_received_ts = 84.073471
current_seq = 444
count = 42
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.713043/434, current 83.903099/444: elapsed=190059.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 83.884094
last_received_ts = 83.883392
last_seq = 443
current_ts = 84.074150
current_received_ts = 84.073441
current_seq = 453
count = 42
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 83.884094/443, current 84.074150/453: elapsed=190055.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 83.903099
last_received_ts = 84.073471
last_seq = 444
current_ts = 84.093163
current_received_ts = 84.263496
current_seq = 454
count = 43
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 83.903099/444, current 84.093163/454: elapsed=190057.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 84.074150
last_received_ts = 84.073441
last_seq = 453
current_ts = 84.264175
current_received_ts = 84.263466
current_seq = 463
count = 43
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 84.074150/453, current 84.264175/463: elapsed=190025.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 84.093163
last_received_ts = 84.263496
last_seq = 454
current_ts = 84.283180
current_received_ts = 84.453514
current_seq = 464
count = 44
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 84.093163/454, current 84.283180/464: elapsed=190020.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 84.264175
last_received_ts = 84.263466
last_seq = 463
current_ts = 84.454208
current_received_ts = 84.453484
current_seq = 473
count = 44
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 84.264175/463, current 84.454208/473: elapsed=190033.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 84.283180
last_received_ts = 84.453514
last_seq = 464
current_ts = 84.473198
current_received_ts = 84.643524
current_seq = 474
count = 45
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 84.283180/464, current 84.473198/474: elapsed=190020.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 84.454208
last_received_ts = 84.453484
last_seq = 473
current_ts = 84.644234
current_received_ts = 84.643501
current_seq = 483
count = 45
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 84.454208/473, current 84.644234/483: elapsed=190019.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 84.473198
last_received_ts = 84.643524
last_seq = 474
current_ts = 84.663216
current_received_ts = 84.833542
current_seq = 484
count = 46
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 84.473198/474, current 84.663216/484: elapsed=190018.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 84.644234
last_received_ts = 84.643501
last_seq = 483
current_ts = 84.834251
current_received_ts = 84.833519
current_seq = 493
count = 46
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 84.644234/483, current 84.834251/493: elapsed=190018.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 84.663216
last_received_ts = 84.833542
last_seq = 484
current_ts = 84.853249
current_received_ts = 85.023560
current_seq = 494
count = 47
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 84.663216/484, current 84.853249/494: elapsed=190032.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 84.834251
last_received_ts = 84.833519
last_seq = 493
current_ts = 85.024246
current_received_ts = 85.023537
current_seq = 503
count = 47
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 84.834251/493, current 85.024246/503: elapsed=190002.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 84.853249
last_received_ts = 85.023560
last_seq = 494
current_ts = 85.043251
current_received_ts = 85.213554
current_seq = 504
count = 48
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 84.853249/494, current 85.043251/504: elapsed=190004.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.024246
last_received_ts = 85.023537
last_seq = 503
current_ts = 85.214264
current_received_ts = 85.213531
current_seq = 513
count = 48
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.024246/503, current 85.214264/513: elapsed=190014.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.043251
last_received_ts = 85.213554
last_seq = 504
current_ts = 85.233261
current_received_ts = 85.403534
current_seq = 514
count = 49
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.043251/504, current 85.233261/514: elapsed=190009.0us expected=190660.6us +- 953.3us, error 0.3%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.214264
last_received_ts = 85.213531
last_seq = 513
current_ts = 85.404243
current_received_ts = 85.403519
current_seq = 523
count = 49
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.214264/513, current 85.404243/523: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.233261
last_received_ts = 85.403534
last_seq = 514
current_ts = 85.423233
current_received_ts = 85.593521
current_seq = 524
count = 50
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.233261/514, current 85.423233/524: elapsed=189970.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.404243
last_received_ts = 85.403519
last_seq = 523
current_ts = 85.594231
current_received_ts = 85.593498
current_seq = 533
count = 50
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.404243/523, current 85.594231/533: elapsed=189984.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.423233
last_received_ts = 85.593521
last_seq = 524
current_ts = 85.613213
current_received_ts = 85.783501
current_seq = 534
count = 51
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.423233/524, current 85.613213/534: elapsed=189982.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.594231
last_received_ts = 85.593498
last_seq = 533
current_ts = 85.784210
current_received_ts = 85.783485
current_seq = 543
count = 51
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.594231/533, current 85.784210/543: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.613213
last_received_ts = 85.783501
last_seq = 534
current_ts = 85.803200
current_received_ts = 85.973488
current_seq = 544
count = 52
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.613213/534, current 85.803200/544: elapsed=189984.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.784210
last_received_ts = 85.783485
last_seq = 543
current_ts = 85.974182
current_received_ts = 85.973465
current_seq = 553
count = 52
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.784210/543, current 85.974182/553: elapsed=189968.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.803200
last_received_ts = 85.973488
last_seq = 544
current_ts = 85.993179
current_received_ts = 86.163467
current_seq = 554
count = 53
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.803200/544, current 85.993179/554: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 85.974182
last_received_ts = 85.973465
last_seq = 553
current_ts = 86.164154
current_received_ts = 86.163445
current_seq = 563
count = 53
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 85.974182/553, current 86.164154/563: elapsed=189974.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 85.993179
last_received_ts = 86.163467
last_seq = 554
current_ts = 86.183151
current_received_ts = 86.353416
current_seq = 564
count = 54
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 85.993179/554, current 86.183151/564: elapsed=189971.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 86.164154
last_received_ts = 86.163445
last_seq = 563
current_ts = 86.354126
current_received_ts = 86.353394
current_seq = 573
count = 54
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 86.164154/563, current 86.354126/573: elapsed=189967.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 86.183151
last_received_ts = 86.353416
last_seq = 564
current_ts = 86.373100
current_received_ts = 86.543365
current_seq = 574
count = 55
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 86.183151/564, current 86.373100/574: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 86.354126
last_received_ts = 86.353394
last_seq = 573
current_ts = 86.544060
current_received_ts = 86.543343
current_seq = 583
count = 55
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 86.354126/573, current 86.544060/583: elapsed=189937.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 86.373100
last_received_ts = 86.543365
last_seq = 574
current_ts = 86.563072
current_received_ts = 86.733315
current_seq = 584
count = 56
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 86.373100/574, current 86.563072/584: elapsed=189967.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 86.544060
last_received_ts = 86.543343
last_seq = 583
current_ts = 86.734032
current_received_ts = 86.733299
current_seq = 593
count = 56
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 86.544060/583, current 86.734032/593: elapsed=189968.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 86.563072
last_received_ts = 86.733315
last_seq = 584
current_ts = 86.753021
current_received_ts = 86.923271
current_seq = 594
count = 57
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 86.563072/584, current 86.753021/594: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 86.734032
last_received_ts = 86.733299
last_seq = 593
current_ts = 86.923981
current_received_ts = 86.923248
current_seq = 603
count = 57
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 86.734032/593, current 86.923981/603: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 86.753021
last_received_ts = 86.923271
last_seq = 594
current_ts = 86.942963
current_received_ts = 87.113220
current_seq = 604
count = 58
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 86.753021/594, current 86.942963/604: elapsed=189938.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 86.923981
last_received_ts = 86.923248
last_seq = 603
current_ts = 87.113930
current_received_ts = 87.113197
current_seq = 613
count = 58
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 86.923981/603, current 87.113930/613: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 86.942963
last_received_ts = 87.113220
last_seq = 604
current_ts = 87.132912
current_received_ts = 87.303146
current_seq = 614
count = 59
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 86.942963/604, current 87.132912/614: elapsed=189950.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 87.113930
last_received_ts = 87.113197
last_seq = 613
current_ts = 87.303856
current_received_ts = 87.303123
current_seq = 623
count = 59
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 87.113930/613, current 87.303856/623: elapsed=189927.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 87.132912
last_received_ts = 87.303146
last_seq = 614
current_ts = 87.322853
current_received_ts = 87.493073
current_seq = 624
count = 60
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 87.132912/614, current 87.322853/624: elapsed=189940.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 87.303856
last_received_ts = 87.303123
last_seq = 623
current_ts = 87.493782
current_received_ts = 87.493050
current_seq = 633
count = 60
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 87.303856/623, current 87.493782/633: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 87.322853
last_received_ts = 87.493073
last_seq = 624
current_ts = 87.512779
current_received_ts = 87.683022
current_seq = 634
count = 61
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 87.322853/624, current 87.512779/634: elapsed=189926.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 87.493782
last_received_ts = 87.493050
last_seq = 633
current_ts = 87.683693
current_received_ts = 87.682983
current_seq = 643
count = 61
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 87.493782/633, current 87.683693/643: elapsed=189910.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 87.512779
last_received_ts = 87.683022
last_seq = 634
current_ts = 87.702705
current_received_ts = 87.872940
current_seq = 644
count = 62
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 87.512779/634, current 87.702705/644: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 87.683693
last_received_ts = 87.682983
last_seq = 643
current_ts = 87.873619
current_received_ts = 87.872910
current_seq = 653
count = 62
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 87.683693/643, current 87.873619/653: elapsed=189927.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 87.702705
last_received_ts = 87.872940
last_seq = 644
current_ts = 87.892616
current_received_ts = 88.062859
current_seq = 654
count = 63
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 87.702705/644, current 87.892616/654: elapsed=189912.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 87.873619
last_received_ts = 87.872910
last_seq = 653
current_ts = 88.063545
current_received_ts = 88.062836
current_seq = 663
count = 63
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 87.873619/653, current 88.063545/663: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 87.892616
last_received_ts = 88.062859
last_seq = 654
current_ts = 88.082535
current_received_ts = 88.252762
current_seq = 664
count = 64
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 87.892616/654, current 88.082535/664: elapsed=189924.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 88.063545
last_received_ts = 88.062836
last_seq = 663
current_ts = 88.253456
current_received_ts = 88.252739
current_seq = 673
count = 64
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 88.063545/663, current 88.253456/673: elapsed=189907.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 88.082535
last_received_ts = 88.252762
last_seq = 664
current_ts = 88.272461
current_received_ts = 88.442665
current_seq = 674
count = 65
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 88.082535/664, current 88.272461/674: elapsed=189921.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 88.253456
last_received_ts = 88.252739
last_seq = 673
current_ts = 88.443367
current_received_ts = 88.442642
current_seq = 683
count = 65
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 88.253456/673, current 88.443367/683: elapsed=189917.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 88.272461
last_received_ts = 88.442665
last_seq = 674
current_ts = 88.462364
current_received_ts = 88.632568
current_seq = 684
count = 66
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 88.272461/674, current 88.462364/684: elapsed=189902.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 88.443367
last_received_ts = 88.442642
last_seq = 683
current_ts = 88.633255
current_received_ts = 88.632545
current_seq = 693
count = 66
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 88.443367/683, current 88.633255/693: elapsed=189888.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 88.462364
last_received_ts = 88.632568
last_seq = 684
current_ts = 88.652260
current_received_ts = 88.822472
current_seq = 694
count = 67
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 88.462364/684, current 88.652260/694: elapsed=189902.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 88.633255
last_received_ts = 88.632545
last_seq = 693
current_ts = 88.823158
current_received_ts = 88.822449
current_seq = 703
count = 67
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 88.633255/693, current 88.823158/703: elapsed=189901.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 88.652260
last_received_ts = 88.822472
last_seq = 694
current_ts = 88.842148
current_received_ts = 89.012367
current_seq = 704
count = 68
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 88.652260/694, current 88.842148/704: elapsed=189887.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 88.823158
last_received_ts = 88.822449
last_seq = 703
current_ts = 89.013077
current_received_ts = 89.012344
current_seq = 713
count = 68
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 88.823158/703, current 89.013077/713: elapsed=189917.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 88.842148
last_received_ts = 89.012367
last_seq = 704
current_ts = 89.032051
current_received_ts = 89.202263
current_seq = 714
count = 69
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 88.842148/704, current 89.032051/714: elapsed=189901.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.013077
last_received_ts = 89.012344
last_seq = 713
current_ts = 89.202957
current_received_ts = 89.202240
current_seq = 723
count = 69
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.013077/713, current 89.202957/723: elapsed=189878.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.032051
last_received_ts = 89.202263
last_seq = 714
current_ts = 89.221939
current_received_ts = 89.392105
current_seq = 724
count = 70
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.032051/714, current 89.221939/724: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.202957
last_received_ts = 89.202240
last_seq = 723
current_ts = 89.392845
current_received_ts = 89.392097
current_seq = 733
count = 70
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.202957/723, current 89.392845/733: elapsed=189888.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.221939
last_received_ts = 89.392105
last_seq = 724
current_ts = 89.411835
current_received_ts = 89.582054
current_seq = 734
count = 71
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.221939/724, current 89.411835/734: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.392845
last_received_ts = 89.392097
last_seq = 733
current_ts = 89.582718
current_received_ts = 89.582024
current_seq = 743
count = 71
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.392845/733, current 89.582718/743: elapsed=189874.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.411835
last_received_ts = 89.582054
last_seq = 734
current_ts = 89.601723
current_received_ts = 89.771912
current_seq = 744
count = 72
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.411835/734, current 89.601723/744: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.582718
last_received_ts = 89.582024
last_seq = 743
current_ts = 89.772598
current_received_ts = 89.771889
current_seq = 753
count = 72
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.582718/743, current 89.772598/753: elapsed=189882.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.601723
last_received_ts = 89.771912
last_seq = 744
current_ts = 89.791603
current_received_ts = 89.961792
current_seq = 754
count = 73
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.601723/744, current 89.791603/754: elapsed=189881.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.772598
last_received_ts = 89.771889
last_seq = 753
current_ts = 89.962479
current_received_ts = 89.961769
current_seq = 763
count = 73
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.772598/753, current 89.962479/763: elapsed=189881.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.791603
last_received_ts = 89.961792
last_seq = 754
current_ts = 89.981468
current_received_ts = 90.151703
current_seq = 764
count = 74
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.791603/754, current 89.981468/764: elapsed=189865.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 89.962479
last_received_ts = 89.961769
last_seq = 763
current_ts = 90.152390
current_received_ts = 90.151680
current_seq = 773
count = 74
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 89.962479/763, current 90.152390/773: elapsed=189911.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 89.981468
last_received_ts = 90.151703
last_seq = 764
current_ts = 90.171387
current_received_ts = 90.341660
current_seq = 774
count = 75
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 89.981468/764, current 90.171387/774: elapsed=189920.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 90.152390
last_received_ts = 90.151680
last_seq = 773
current_ts = 90.342369
current_received_ts = 90.341637
current_seq = 783
count = 75
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 90.152390/773, current 90.342369/783: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 90.171387
last_received_ts = 90.341660
last_seq = 774
current_ts = 90.361366
current_received_ts = 90.531631
current_seq = 784
count = 76
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 90.171387/774, current 90.361366/784: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 90.342369
last_received_ts = 90.341637
last_seq = 783
current_ts = 90.532318
current_received_ts = 90.531609
current_seq = 793
count = 76
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 90.342369/783, current 90.532318/793: elapsed=189947.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 90.361366
last_received_ts = 90.531631
last_seq = 784
current_ts = 90.551315
current_received_ts = 90.721588
current_seq = 794
count = 77
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 90.361366/784, current 90.551315/794: elapsed=189946.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 90.532318
last_received_ts = 90.531609
last_seq = 793
current_ts = 90.722298
current_received_ts = 90.721565
current_seq = 803
count = 77
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 90.532318/793, current 90.722298/803: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = flip
last_ts = 90.551315
last_received_ts = 90.721588
last_seq = 794
current_ts = 90.741272
current_received_ts = 90.911552
current_seq = 804
count = 78
seq_step = 1
(kms_flip:2073) DEBUG: flip ts/seq: last 90.551315/794, current 90.741272/804: elapsed=189963.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: name = vblank
last_ts = 90.722298
last_received_ts = 90.721565
last_seq = 803
current_ts = 90.912254
current_received_ts = 90.911530
current_seq = 813
count = 78
seq_step = 10
(kms_flip:2073) DEBUG: vblank ts/seq: last 90.722298/803, current 90.912254/813: elapsed=189962.0us expected=190660.6us +- 953.3us, error 0.4%
(kms_flip:2073) DEBUG: expected 787, counted 790, encoder type 2
(kms_flip:2073) DEBUG: dropped frames, expected 787, counted 790, encoder type 2
(kms_flip:2073) DEBUG: expected 787, counted 790, encoder type 2
(kms_flip:2073) DEBUG: dropped frames, expected 787, counted 790, encoder type 2
(kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2073) igt_core-INFO: Stack trace:
(kms_flip:2073) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:2073) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2073) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2073) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2073) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2073) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2073) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2073) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A2: FAIL (18.624s)
Subtest flip-vs-blocking-wf-vblank: FAIL (25.369s)
(kms_flip:2073) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246:
(kms_flip:2073) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2073) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2073) igt_kms-CRITICAL: DPMS property not found on 154
Dmesg
<6> [75.094929] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [75.095251] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:172]
<7> [75.095320] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:173]
<7> [75.129395] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [75.129554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [75.130015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [75.130473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [75.130885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [75.131313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [75.131714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [75.132130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [75.132526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [75.132882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [75.133218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [75.133537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [75.133856] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [75.134197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [75.134516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [75.134832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [75.135171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [75.135487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [75.135789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [75.136044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [75.136289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [75.136446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [75.136604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [75.136764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [75.136923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [75.137103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [75.137264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [75.137421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [75.137579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [75.137736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [75.137893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [75.138065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [75.138223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [75.138381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [75.138538] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [75.138695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [75.138857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [75.139030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [75.139194] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [75.139356] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [75.139538] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [75.139696] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [75.139887] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [75.140052] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [75.140208] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32: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> [75.140368] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [75.140528] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [75.140686] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [75.140843] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [75.141015] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [75.141194] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [75.141357] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [75.141521] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [75.141683] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [75.141846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [75.142012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [75.142172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [75.142338] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [75.142501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [75.154102] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [75.173494] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [75.174191] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [75.174899] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [75.175541] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [75.176206] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [75.176833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [75.177457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [75.178087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [75.178663] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [75.178824] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [75.178981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [75.179136] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [75.179344] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [75.179550] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [75.179736] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [75.180091] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [75.180216] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [75.180236] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2]
<7> [75.180286] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [75.180464] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [75.180620] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [75.180785] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:134:pipe B]
<7> [75.180952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [75.181116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [75.181284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [75.181450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [75.181612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [75.181773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [75.181941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [75.182101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [75.182260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [75.182419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [75.182578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [75.182737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [75.182901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [75.183059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [75.183245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [75.183410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [75.183572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [75.183733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [75.183909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [75.184069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [75.184228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [75.184387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [75.184578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [75.184822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [75.185052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [75.185273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [75.185493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [75.185714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [75.185949] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [75.186170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [75.186390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [75.186618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [75.186786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [75.186948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [75.187109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [75.187271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [75.187432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [75.187593] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [75.187777] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [75.187939] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [75.188148] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [75.188313] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [75.188475] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [75.188635] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [75.188845] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [75.189005] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [75.189164] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [75.189333] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [75.189501] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [75.189689] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:84:plane 1B] min cdclk (69965 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz)
<7> [75.189863] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [75.190028] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [75.190189] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [75.190350] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [75.190513] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [75.190674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [75.190845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [75.191012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [75.191175] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [75.191336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [75.191496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [75.191655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [75.191838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [75.191996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [75.192155] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [75.192313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [75.192473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [75.192632] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [75.192800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [75.192958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [75.193116] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [75.193274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [75.193430] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [75.193589] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [75.193754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [75.193911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [75.194070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [75.194227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [75.194385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [75.194543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.194700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.194866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [75.195178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [75.195333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [75.195980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:172] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [75.196138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [75.196294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [75.196452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [75.196609] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [75.196780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [75.196944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [75.197106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [75.197697] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [75.197904] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [75.198064] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [75.198222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [75.198379] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [75.198535] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [75.198690] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [75.198855] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [75.199044] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [75.199242] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [75.199406] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [75.199621] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [75.199826] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [75.219660] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [75.219862] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [93.718967] [IGT] kms_flip: finished subtest B-HDMI-A2, FAIL
<6> [93.720373] [IGT] kms_flip: finished subtest flip-vs-blocking-wf-vblank, FAIL
<7> [93.737089] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:172] from all active usage due to RMFB ioctl
<7> [93.737369] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:172] is removed
<7> [93.737454] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [93.737785] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [93.738061] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [93.738333] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [93.738619] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [93.738885] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [93.739164] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [93.762344] [IGT] kms_flip: exiting, ret=98
<7> [93.777177] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [93.777385] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [93.777560] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [93.777743] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [93.777909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [93.778080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [93.778248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [93.778413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [93.778585] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [93.778749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [93.778910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [93.779071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [93.779233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [93.779393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [93.779570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [93.779735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [93.779898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [93.780059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [93.780219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [93.780379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [93.780710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [93.780871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [93.781031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [93.781190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [93.781349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [93.781524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [93.781683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [93.781841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [93.781999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [93.782156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [93.782314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [93.782482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [93.782640] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [93.782798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [93.782955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [93.783114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [93.783272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [93.783431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [93.783602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [93.783760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [93.783919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [93.784077] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [93.784236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [93.784393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [93.784560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [93.784718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [93.784876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [93.785035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [93.785194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [93.785354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [93.785519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [93.785678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [93.785836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [93.785995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [93.786154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [93.786311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [93.786475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [93.786632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [93.786790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [93.786948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [93.787106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [93.787263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [93.787420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [93.787590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [93.787748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [93.787906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [93.788064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [93.788221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [93.788378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [93.788552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [93.788710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [93.788868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [93.789027] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [93.789184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [93.789342] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [93.789514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [93.789673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [93.789832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [93.789990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [93.790148] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [93.790306] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [93.790535] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x1
<7> [93.790767] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x1
<7> [93.791026] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [93.791250] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [93.791495] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32: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> [93.791720] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [93.791943] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [93.792110] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [93.792269] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [93.792436] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [93.792607] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [93.792775] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [93.792954] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [93.793121] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [93.793282] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [93.793454] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [93.793620] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [93.793784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [93.793947] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [93.794108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [93.794269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [93.794441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [93.794605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [93.794767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [93.794928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [93.795087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [93.795247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [93.795407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [93.795580] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [93.795739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [93.795899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [93.796059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [93.796217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [93.796374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [93.796543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [93.796700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [93.796858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [93.797015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [93.797179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [93.797346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [93.797547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [93.797708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.797869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [93.798348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [93.798515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [93.799147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:168] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [93.799305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [93.799472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [93.799630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [93.799788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [93.799944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [93.800101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [93.800257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [93.800413] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [93.800581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [93.800739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [93.800895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [93.801051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [93.801207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [93.801363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [93.801666] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [93.814429] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [93.814617] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [93.814804] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [93.814976] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [93.815137] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [93.815296] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [93.815464] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [93.815624] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [93.815786] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [93.815952] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [93.816165] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [93.816331] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [93.816560] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [93.816759] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [93.817135] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [93.817301] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [93.836204] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [93.836426] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [93.836890] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [93.837402] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
Created at 2024-12-06 05:24:56