Results for igt@kms_flip@wf_vblank-ts-check@b-hdmi-a2

Machine description: shard-rkl-1

Result: Fail

git-log-oneline i915_display_info23 igt_runner23 runtimes23 results23.json results23-i915-load.json i915_display_info_post_exec23 boot23 dmesg23

DetailValue
Duration 18.67 seconds
Hostname 
shard-rkl-1
Igt-Version 
IGT-Version: 1.30-g2f9077a21 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16231-g3ccfd8016ad8+ 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 18873.0us +- 9.374us accuracy 0.15%
vblank interval differs from modeline! expected 18973.8us, measured 18873us +- 9.374us, difference 100.8us (10.8 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __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.671s)
Subtest wf_vblank-ts-check: FAIL (26.013s)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2246 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2934 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2390 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:2058) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2058) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2058) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2058) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:2058) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2058) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2058) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=7680)
(kms_flip:2058) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2058) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2058) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2058) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2058) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2058) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2058) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2058) igt_kms-INFO:   1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2058) DEBUG: No stale events found
(kms_flip:2058) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2058) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2058) INFO: Expected frametime: 18974us; measured 18873.0us +- 9.374us accuracy 0.15%
(kms_flip:2058) INFO: vblank interval differs from modeline! expected 18973.8us, measured 18873us +- 9.374us, difference 100.8us (10.8 sigma)
(kms_flip:2058) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 72.838509
current_received_ts = 72.837791
current_seq = 30
count = 0
seq_step = 10
(kms_flip:2058) DEBUG: name = vblank
last_ts = 72.838509
last_received_ts = 72.837791
last_seq = 30
current_ts = 73.027229
current_received_ts = 73.026535
current_seq = 40
count = 1
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 72.838509/30, current 73.027229/40: elapsed=188725.0us expected=188730.0us +- 943.6us, error 0.0%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.027229
last_received_ts = 73.026535
last_seq = 40
current_ts = 73.216103
current_received_ts = 73.215385
current_seq = 50
count = 2
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.027229/40, current 73.216103/50: elapsed=188875.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.216103
last_received_ts = 73.215385
last_seq = 50
current_ts = 73.404968
current_received_ts = 73.404251
current_seq = 60
count = 3
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.216103/50, current 73.404968/60: elapsed=188866.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.404968
last_received_ts = 73.404251
last_seq = 60
current_ts = 73.593834
current_received_ts = 73.593102
current_seq = 70
count = 4
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.404968/60, current 73.593834/70: elapsed=188861.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.593834
last_received_ts = 73.593102
last_seq = 70
current_ts = 73.782700
current_received_ts = 73.782051
current_seq = 80
count = 5
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.593834/70, current 73.782700/80: elapsed=188868.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.782700
last_received_ts = 73.782051
last_seq = 80
current_ts = 73.971550
current_received_ts = 73.970863
current_seq = 90
count = 6
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.782700/80, current 73.971550/90: elapsed=188848.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 73.971550
last_received_ts = 73.970863
last_seq = 90
current_ts = 74.160492
current_received_ts = 74.159775
current_seq = 100
count = 7
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 73.971550/90, current 74.160492/100: elapsed=188945.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 74.160492
last_received_ts = 74.159775
last_seq = 100
current_ts = 74.349449
current_received_ts = 74.348755
current_seq = 110
count = 8
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 74.160492/100, current 74.349449/110: elapsed=188959.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 74.349449
last_received_ts = 74.348755
last_seq = 110
current_ts = 74.538429
current_received_ts = 74.537727
current_seq = 120
count = 9
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 74.349449/110, current 74.538429/120: elapsed=188974.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 74.538429
last_received_ts = 74.537727
last_seq = 120
current_ts = 74.727409
current_received_ts = 74.726700
current_seq = 130
count = 10
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 74.538429/120, current 74.727409/130: elapsed=188986.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 74.727409
last_received_ts = 74.726700
last_seq = 130
current_ts = 74.916389
current_received_ts = 74.915672
current_seq = 140
count = 11
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 74.727409/130, current 74.916389/140: elapsed=188975.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 74.916389
last_received_ts = 74.915672
last_seq = 140
current_ts = 75.105385
current_received_ts = 75.104668
current_seq = 150
count = 12
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 74.916389/140, current 75.105385/150: elapsed=189001.0us expected=188730.0us +- 943.6us, error 0.1%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 75.105385
last_received_ts = 75.104668
last_seq = 150
current_ts = 75.294441
current_received_ts = 75.293739
current_seq = 160
count = 13
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 75.105385/150, current 75.294441/160: elapsed=189055.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 75.294441
last_received_ts = 75.293739
last_seq = 160
current_ts = 75.483528
current_received_ts = 75.482811
current_seq = 170
count = 14
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 75.294441/160, current 75.483528/170: elapsed=189086.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 75.483528
last_received_ts = 75.482811
last_seq = 170
current_ts = 75.672585
current_received_ts = 75.671883
current_seq = 180
count = 15
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 75.483528/170, current 75.672585/180: elapsed=189053.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 75.672585
last_received_ts = 75.671883
last_seq = 180
current_ts = 75.861664
current_received_ts = 75.860970
current_seq = 190
count = 16
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 75.672585/180, current 75.861664/190: elapsed=189084.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 75.861664
last_received_ts = 75.860970
last_seq = 190
current_ts = 76.050728
current_received_ts = 76.050034
current_seq = 200
count = 17
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 75.861664/190, current 76.050728/200: elapsed=189062.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.050728
last_received_ts = 76.050034
last_seq = 200
current_ts = 76.239883
current_received_ts = 76.239182
current_seq = 210
count = 18
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.050728/200, current 76.239883/210: elapsed=189152.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.239883
last_received_ts = 76.239182
last_seq = 210
current_ts = 76.429047
current_received_ts = 76.428329
current_seq = 220
count = 19
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.239883/210, current 76.429047/220: elapsed=189167.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.429047
last_received_ts = 76.428329
last_seq = 220
current_ts = 76.618202
current_received_ts = 76.617485
current_seq = 230
count = 20
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.429047/220, current 76.618202/230: elapsed=189154.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.618202
last_received_ts = 76.617485
last_seq = 230
current_ts = 76.807358
current_received_ts = 76.806641
current_seq = 240
count = 21
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.618202/230, current 76.807358/240: elapsed=189154.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.807358
last_received_ts = 76.806641
last_seq = 240
current_ts = 76.996506
current_received_ts = 76.995789
current_seq = 250
count = 22
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.807358/240, current 76.996506/250: elapsed=189153.0us expected=188730.0us +- 943.6us, error 0.2%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 76.996506
last_received_ts = 76.995789
last_seq = 250
current_ts = 77.185722
current_received_ts = 77.185028
current_seq = 260
count = 23
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 76.996506/250, current 77.185722/260: elapsed=189211.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 77.185722
last_received_ts = 77.185028
last_seq = 260
current_ts = 77.374947
current_received_ts = 77.374222
current_seq = 270
count = 24
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 77.185722/260, current 77.374947/270: elapsed=189225.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 77.374947
last_received_ts = 77.374222
last_seq = 270
current_ts = 77.564171
current_received_ts = 77.563454
current_seq = 280
count = 25
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 77.374947/270, current 77.564171/280: elapsed=189225.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 77.564171
last_received_ts = 77.563454
last_seq = 280
current_ts = 77.753395
current_received_ts = 77.752678
current_seq = 290
count = 26
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 77.564171/280, current 77.753395/290: elapsed=189227.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 77.753395
last_received_ts = 77.752678
last_seq = 290
current_ts = 77.942619
current_received_ts = 77.941910
current_seq = 300
count = 27
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 77.753395/290, current 77.942619/300: elapsed=189225.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 77.942619
last_received_ts = 77.941910
last_seq = 300
current_ts = 78.131882
current_received_ts = 78.131165
current_seq = 310
count = 28
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 77.942619/300, current 78.131882/310: elapsed=189259.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 78.131882
last_received_ts = 78.131165
last_seq = 310
current_ts = 78.321167
current_received_ts = 78.320450
current_seq = 320
count = 29
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 78.131882/310, current 78.321167/320: elapsed=189289.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 78.321167
last_received_ts = 78.320450
last_seq = 320
current_ts = 78.510460
current_received_ts = 78.509743
current_seq = 330
count = 30
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 78.321167/320, current 78.510460/330: elapsed=189290.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 78.510460
last_received_ts = 78.509743
last_seq = 330
current_ts = 78.699753
current_received_ts = 78.699036
current_seq = 340
count = 31
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 78.510460/330, current 78.699753/340: elapsed=189291.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 78.699753
last_received_ts = 78.699036
last_seq = 340
current_ts = 78.889038
current_received_ts = 78.888321
current_seq = 350
count = 32
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 78.699753/340, current 78.889038/350: elapsed=189290.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 78.889038
last_received_ts = 78.888321
last_seq = 350
current_ts = 79.078331
current_received_ts = 79.077599
current_seq = 360
count = 33
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 78.889038/350, current 79.078331/360: elapsed=189293.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 79.078331
last_received_ts = 79.077599
last_seq = 360
current_ts = 79.267677
current_received_ts = 79.266945
current_seq = 370
count = 34
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 79.078331/360, current 79.267677/370: elapsed=189346.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 79.267677
last_received_ts = 79.266945
last_seq = 370
current_ts = 79.457024
current_received_ts = 79.456299
current_seq = 380
count = 35
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 79.267677/370, current 79.457024/380: elapsed=189346.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 79.457024
last_received_ts = 79.456299
last_seq = 380
current_ts = 79.646370
current_received_ts = 79.645638
current_seq = 390
count = 36
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 79.457024/380, current 79.646370/390: elapsed=189347.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 79.646370
last_received_ts = 79.645638
last_seq = 390
current_ts = 79.835716
current_received_ts = 79.834991
current_seq = 400
count = 37
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 79.646370/390, current 79.835716/400: elapsed=189347.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 79.835716
last_received_ts = 79.834991
last_seq = 400
current_ts = 80.025063
current_received_ts = 80.024330
current_seq = 410
count = 38
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 79.835716/400, current 80.025063/410: elapsed=189346.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.025063
last_received_ts = 80.024330
last_seq = 410
current_ts = 80.214462
current_received_ts = 80.213760
current_seq = 420
count = 39
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.025063/410, current 80.214462/420: elapsed=189398.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.214462
last_received_ts = 80.213760
last_seq = 420
current_ts = 80.403854
current_received_ts = 80.403145
current_seq = 430
count = 40
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.214462/420, current 80.403854/430: elapsed=189395.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.403854
last_received_ts = 80.403145
last_seq = 430
current_ts = 80.593239
current_received_ts = 80.592545
current_seq = 440
count = 41
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.403854/430, current 80.593239/440: elapsed=189379.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.593239
last_received_ts = 80.592545
last_seq = 440
current_ts = 80.782646
current_received_ts = 80.781929
current_seq = 450
count = 42
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.593239/440, current 80.782646/450: elapsed=189411.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.782646
last_received_ts = 80.781929
last_seq = 450
current_ts = 80.972031
current_received_ts = 80.971329
current_seq = 460
count = 43
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.782646/450, current 80.972031/460: elapsed=189379.0us expected=188730.0us +- 943.6us, error 0.3%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 80.972031
last_received_ts = 80.971329
last_seq = 460
current_ts = 81.161469
current_received_ts = 81.160744
current_seq = 470
count = 44
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 80.972031/460, current 81.161469/470: elapsed=189439.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 81.161469
last_received_ts = 81.160744
last_seq = 470
current_ts = 81.350899
current_received_ts = 81.350166
current_seq = 480
count = 45
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 81.161469/470, current 81.350899/480: elapsed=189433.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 81.350899
last_received_ts = 81.350166
last_seq = 480
current_ts = 81.540329
current_received_ts = 81.539627
current_seq = 490
count = 46
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 81.350899/480, current 81.540329/490: elapsed=189427.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 81.540329
last_received_ts = 81.539627
last_seq = 490
current_ts = 81.729782
current_received_ts = 81.729065
current_seq = 500
count = 47
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 81.540329/490, current 81.729782/500: elapsed=189454.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 81.729782
last_received_ts = 81.729065
last_seq = 500
current_ts = 81.919212
current_received_ts = 81.918503
current_seq = 510
count = 48
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 81.729782/500, current 81.919212/510: elapsed=189436.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 81.919212
last_received_ts = 81.918503
last_seq = 510
current_ts = 82.108665
current_received_ts = 82.107948
current_seq = 520
count = 49
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 81.919212/510, current 82.108665/520: elapsed=189453.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 82.108665
last_received_ts = 82.107948
last_seq = 520
current_ts = 82.298141
current_received_ts = 82.297424
current_seq = 530
count = 50
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 82.108665/520, current 82.298141/530: elapsed=189476.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 82.298141
last_received_ts = 82.297424
last_seq = 530
current_ts = 82.487617
current_received_ts = 82.486900
current_seq = 540
count = 51
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 82.298141/530, current 82.487617/540: elapsed=189473.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 82.487617
last_received_ts = 82.486900
last_seq = 540
current_ts = 82.677078
current_received_ts = 82.676376
current_seq = 550
count = 52
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 82.487617/540, current 82.677078/550: elapsed=189462.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 82.677078
last_received_ts = 82.676376
last_seq = 550
current_ts = 82.866570
current_received_ts = 82.865852
current_seq = 560
count = 53
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 82.677078/550, current 82.866570/560: elapsed=189490.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 82.866570
last_received_ts = 82.865852
last_seq = 560
current_ts = 83.056030
current_received_ts = 83.055336
current_seq = 570
count = 54
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 82.866570/560, current 83.056030/570: elapsed=189463.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 83.056030
last_received_ts = 83.055336
last_seq = 570
current_ts = 83.245560
current_received_ts = 83.244843
current_seq = 580
count = 55
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 83.056030/570, current 83.245560/580: elapsed=189524.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 83.245560
last_received_ts = 83.244843
last_seq = 580
current_ts = 83.435051
current_received_ts = 83.434349
current_seq = 590
count = 56
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 83.245560/580, current 83.435051/590: elapsed=189492.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 83.435051
last_received_ts = 83.434349
last_seq = 590
current_ts = 83.624573
current_received_ts = 83.623856
current_seq = 600
count = 57
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 83.435051/590, current 83.624573/600: elapsed=189522.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 83.624573
last_received_ts = 83.623856
last_seq = 600
current_ts = 83.814064
current_received_ts = 83.813362
current_seq = 610
count = 58
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 83.624573/600, current 83.814064/610: elapsed=189494.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 83.814064
last_received_ts = 83.813362
last_seq = 610
current_ts = 84.003586
current_received_ts = 84.002869
current_seq = 620
count = 59
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 83.814064/610, current 84.003586/620: elapsed=189524.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.003586
last_received_ts = 84.002869
last_seq = 620
current_ts = 84.193108
current_received_ts = 84.192406
current_seq = 630
count = 60
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.003586/620, current 84.193108/630: elapsed=189516.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.193108
last_received_ts = 84.192406
last_seq = 630
current_ts = 84.382660
current_received_ts = 84.381943
current_seq = 640
count = 61
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.193108/630, current 84.382660/640: elapsed=189552.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.382660
last_received_ts = 84.381943
last_seq = 640
current_ts = 84.572197
current_received_ts = 84.571472
current_seq = 650
count = 62
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.382660/640, current 84.572197/650: elapsed=189537.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.572197
last_received_ts = 84.571472
last_seq = 650
current_ts = 84.761734
current_received_ts = 84.761040
current_seq = 660
count = 63
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.572197/650, current 84.761734/660: elapsed=189537.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.761734
last_received_ts = 84.761040
last_seq = 660
current_ts = 84.951248
current_received_ts = 84.950554
current_seq = 670
count = 64
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.761734/660, current 84.951248/670: elapsed=189521.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 84.951248
last_received_ts = 84.950554
last_seq = 670
current_ts = 85.140800
current_received_ts = 85.140099
current_seq = 680
count = 65
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 84.951248/670, current 85.140800/680: elapsed=189550.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 85.140800
last_received_ts = 85.140099
last_seq = 680
current_ts = 85.330383
current_received_ts = 85.329659
current_seq = 690
count = 66
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 85.140800/680, current 85.330383/690: elapsed=189578.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 85.330383
last_received_ts = 85.329659
last_seq = 690
current_ts = 85.519943
current_received_ts = 85.519226
current_seq = 700
count = 67
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 85.330383/690, current 85.519943/700: elapsed=189562.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 85.519943
last_received_ts = 85.519226
last_seq = 700
current_ts = 85.709503
current_received_ts = 85.708786
current_seq = 710
count = 68
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 85.519943/700, current 85.709503/710: elapsed=189562.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 85.709503
last_received_ts = 85.708786
last_seq = 710
current_ts = 85.899055
current_received_ts = 85.898361
current_seq = 720
count = 69
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 85.709503/710, current 85.899055/720: elapsed=189548.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 85.899055
last_received_ts = 85.898361
last_seq = 720
current_ts = 86.088631
current_received_ts = 86.087914
current_seq = 730
count = 70
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 85.899055/720, current 86.088631/730: elapsed=189580.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 86.088631
last_received_ts = 86.087914
last_seq = 730
current_ts = 86.278214
current_received_ts = 86.277512
current_seq = 740
count = 71
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 86.088631/730, current 86.278214/740: elapsed=189585.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 86.278214
last_received_ts = 86.277512
last_seq = 740
current_ts = 86.467804
current_received_ts = 86.467087
current_seq = 750
count = 72
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 86.278214/740, current 86.467804/750: elapsed=189584.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 86.467804
last_received_ts = 86.467087
last_seq = 750
current_ts = 86.657372
current_received_ts = 86.656670
current_seq = 760
count = 73
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 86.467804/750, current 86.657372/760: elapsed=189568.0us expected=188730.0us +- 943.6us, error 0.4%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 86.657372
last_received_ts = 86.656670
last_seq = 760
current_ts = 86.846954
current_received_ts = 86.846252
current_seq = 770
count = 74
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 86.657372/760, current 86.846954/770: elapsed=189583.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 86.846954
last_received_ts = 86.846252
last_seq = 770
current_ts = 87.036537
current_received_ts = 87.035835
current_seq = 780
count = 75
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 86.846954/770, current 87.036537/780: elapsed=189584.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 87.036537
last_received_ts = 87.035835
last_seq = 780
current_ts = 87.226135
current_received_ts = 87.225433
current_seq = 790
count = 76
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 87.036537/780, current 87.226135/790: elapsed=189603.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 87.226135
last_received_ts = 87.225433
last_seq = 790
current_ts = 87.415741
current_received_ts = 87.415039
current_seq = 800
count = 77
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 87.226135/790, current 87.415741/800: elapsed=189603.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 87.415741
last_received_ts = 87.415039
last_seq = 800
current_ts = 87.605362
current_received_ts = 87.604691
current_seq = 810
count = 78
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 87.415741/800, current 87.605362/810: elapsed=189620.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: name = vblank
last_ts = 87.605362
last_received_ts = 87.604691
last_seq = 810
current_ts = 87.794945
current_received_ts = 87.794258
current_seq = 820
count = 79
seq_step = 10
(kms_flip:2058) DEBUG: vblank ts/seq: last 87.605362/810, current 87.794945/820: elapsed=189586.0us expected=188730.0us +- 943.6us, error 0.5%
(kms_flip:2058) DEBUG: expected 802, counted 800, encoder type 2
(kms_flip:2058) DEBUG: dropped frames, expected 802, counted 800, encoder type 2
(kms_flip:2058) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2058) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2058) igt_core-INFO: Stack trace:
(kms_flip:2058) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2058) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2058) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2058) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2058) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2058) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2058) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2058) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A2: FAIL (18.671s)
Subtest wf_vblank-ts-check: FAIL (26.013s)
(kms_flip:2058) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246:
(kms_flip:2058) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2058) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2058) igt_kms-CRITICAL: DPMS property not found on 147
Dmesg
<6> [72.502019] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [72.502408] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:166]
<7> [72.502526] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167]
<7> [72.542233] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [72.542351] 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> [72.542679] 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> [72.542959] 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> [72.543286] 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> [72.543619] 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> [72.543978] 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> [72.544312] 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> [72.544643] 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> [72.544993] 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> [72.545326] 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> [72.545653] 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> [72.546050] 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> [72.546334] 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> [72.546595] 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> [72.546872] 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> [72.547129] 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> [72.547390] 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> [72.547644] 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> [72.547917] 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> [72.548171] 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> [72.548428] 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> [72.548691] 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> [72.548946] 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> [72.549135] 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> [72.549275] 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> [72.549413] 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> [72.549552] 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> [72.549697] 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> [72.549837] 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> [72.549976] 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> [72.550118] 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> [72.550261] 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> [72.550404] 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> [72.550547] 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> [72.550687] 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> [72.550840] 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> [72.550983] 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> [72.551126] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [72.551268] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [72.551431] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [72.551573] 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> [72.551753] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [72.551895] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [72.552036] 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> [72.552176] 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> [72.552315] 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> [72.552455] 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> [72.552598] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [72.552751] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [72.552910] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [72.553053] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [72.553197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [72.553341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [72.553487] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [72.553630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [72.553782] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [72.553926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [72.554069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [72.567348] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [72.587944] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [72.588538] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [72.589198] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [72.589768] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [72.590371] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [72.590939] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [72.591510] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [72.592070] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [72.592602] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [72.593155] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [72.593849] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [72.594523] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [72.595171] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [72.596272] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [72.596687] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [72.596749] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:147:HDMI-A-2]
<7> [72.596906] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:147:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [72.597472] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [72.597918] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [72.598401] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:146:DDI TC1/PHY C] [CRTC:134:pipe B]
<7> [72.598794] 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> [72.599225] 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> [72.599628] 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> [72.600040] 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> [72.600390] 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> [72.600720] 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> [72.601069] 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> [72.601409] 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> [72.601715] 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> [72.602027] 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> [72.602323] 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> [72.602624] 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> [72.602920] 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> [72.603234] 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> [72.603499] 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> [72.603743] 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> [72.603992] 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> [72.604236] 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> [72.604456] 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> [72.604673] 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> [72.604890] 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> [72.605121] 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> [72.605338] 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> [72.605554] 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> [72.605770] 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> [72.605997] 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> [72.606214] 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> [72.606411] 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> [72.606574] 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> [72.606736] 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> [72.606899] 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> [72.607069] 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> [72.607231] 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> [72.607396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state
<7> [72.607542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [72.607687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, 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> [72.607834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [72.607980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [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> [72.608142] 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> [72.608295] 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> [72.608445] 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> [72.608593] 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> [72.608739] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [72.608904] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [72.609062] 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> [72.609248] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [72.609388] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [72.609527] 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> [72.609665] 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> [72.609803] 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> [72.609942] 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> [72.610093] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [72.610240] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [72.610382] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [72.610532] 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> [72.610679] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [72.610826] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [72.610968] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [72.611120] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [72.611265] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [72.611409] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [72.611553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [72.611696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [72.611838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [72.611979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [72.612128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [72.612272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [72.612414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [72.612555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [72.612694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [72.612833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [72.612973] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [72.613122] 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> [72.613262] 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> [72.613401] 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> [72.613549] 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> [72.613689] 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> [72.613829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [72.613968] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [72.614113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [72.614253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [72.614391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [72.614530] 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> [72.614669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [72.614807] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [72.614945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [72.615089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.615227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.615365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.615503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [72.615642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [72.615779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.615918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.616070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [72.616207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [72.616345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:166] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [72.616484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [72.616623] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [72.616762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [72.616900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [72.617051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [72.617189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [72.617327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [72.618040] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [72.618223] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [72.618368] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [72.618510] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [72.618650] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [72.618788] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [72.618953] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [72.619136] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [72.619281] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [72.619529] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [72.619709] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [72.639518] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [72.639706] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [91.173296] [IGT] kms_flip: finished subtest B-HDMI-A2, FAIL
<6> [91.173690] [IGT] kms_flip: finished subtest wf_vblank-ts-check, FAIL
<7> [91.182356] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:167] from all active usage due to RMFB ioctl
<7> [91.182555] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:167] is removed
<7> [91.182632] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [91.182826] 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> [91.182993] 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> [91.183178] 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> [91.183360] 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> [91.183528] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [91.183700] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [91.208250] [IGT] kms_flip: exiting, ret=98
<7> [91.219173] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:147:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [91.219367] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [91.219508] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [91.219666] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:146:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [91.219808] 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> [91.219952] 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> [91.220110] 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> [91.220257] 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> [91.220403] 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> [91.220547] 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> [91.220689] 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> [91.220830] 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> [91.220971] 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> [91.221117] 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> [91.221262] 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> [91.221406] 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> [91.221549] 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> [91.221688] 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> [91.221828] 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> [91.221968] 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> [91.222114] 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> [91.222254] 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> [91.222394] 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> [91.222533] 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> [91.222673] 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> [91.222812] 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> [91.222951] 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> [91.223095] 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> [91.223239] 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> [91.223384] 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> [91.223526] 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> [91.223668] 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> [91.223810] 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> [91.223950] 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> [91.224101] 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> [91.224244] 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> [91.224384] 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> [91.224525] 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> [91.224666] 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> [91.224806] 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> [91.224945] 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> [91.225085] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [91.225431] 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> [91.225572] 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> [91.225713] 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> [91.225852] 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> [91.225992] 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> [91.226165] 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> [91.226310] 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> [91.226455] 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> [91.226597] 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> [91.226738] 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> [91.226879] 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> [91.227018] 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> [91.227187] 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> [91.227327] 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> [91.227469] 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> [91.227610] 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> [91.227749] 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> [91.227890] 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> [91.228034] 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> [91.228217] 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> [91.228361] 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> [91.228503] 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> [91.228643] 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> [91.228783] 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> [91.228923] 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> [91.229063] 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> [91.229237] 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> [91.229377] 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> [91.229517] 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> [91.229657] 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> [91.229796] 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> [91.229935] 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> [91.230076] 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> [91.230247] 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> [91.230387] 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> [91.230526] 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> [91.230666] 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> [91.230806] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [91.230947] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [91.231203] 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> [91.231345] 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> [91.231501] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [91.231641] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [91.231783] 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> [91.231925] 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> [91.232064] 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> [91.232219] 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> [91.232365] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [91.232504] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [91.232650] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [91.232792] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [91.232953] 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> [91.233903] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [91.234053] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [91.234209] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [91.234356] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [91.234503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [91.234648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [91.234794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [91.234939] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [91.235082] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [91.235234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [91.235375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [91.235515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [91.235655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [91.235795] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [91.235935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [91.236075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [91.236229] 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> [91.236374] 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> [91.236514] 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> [91.236655] 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> [91.236793] 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> [91.236933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [91.237072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [91.237222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [91.237361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [91.237500] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [91.237639] 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> [91.237779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [91.237918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [91.238057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [91.238205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.238343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.238482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.238621] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [91.238760] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [91.238898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.239037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.239182] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.239320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [91.239458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:162] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [91.239599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [91.239736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [91.239875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [91.240014] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [91.240165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [91.240307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [91.240445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [91.240583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [91.240721] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [91.240860] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [91.240999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [91.241192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [91.241331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [91.241470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [91.241977] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [91.253102] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [91.253353] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [91.253567] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [91.253722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [91.253865] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [91.254006] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [91.254159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [91.254303] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [91.254524] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [91.254668] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [91.255232] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [91.255418] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [91.255893] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [91.256041] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [91.274945] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:147:HDMI-A-2]
<7> [91.275197] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [91.275812] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [91.276480] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2025-03-06 11:07:51