Results for igt@kms_flip@blocking-wf_vblank@b-hdmi-a1

Machine description: shard-rkl-7

Result: Fail

integration-manifest git-log-oneline i915_display_info1 igt_runner1 run1 runtimes1 results1.json i915_display_info_post_exec1 boot1 dmesg1

DetailValue
Duration 18.48 seconds
Hostname 
shard-rkl-7
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc3-CI_DRM_15561-gcebb76fe419d+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A1
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19027.4us +- 8.648us accuracy 0.14%
vblank interval differs from modeline! expected 18973.8us, measured 19027us +- 8.648us, difference 53.6us (6.2 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-A1: FAIL (18.478s)
Subtest blocking-wf_vblank: FAIL (27.527s)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2234 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-A1
(kms_flip:1227) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1227) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:1227) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1227) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:1227) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1227) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1227) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=7680)
(kms_flip:1227) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1227) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1227) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1227) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1227) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1227) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1227) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1227) igt_kms-INFO:   1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
(kms_flip:1227) DEBUG: No stale events found
(kms_flip:1227) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:1227) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:1227) INFO: Expected frametime: 18974us; measured 19027.4us +- 8.648us accuracy 0.14%
(kms_flip:1227) INFO: vblank interval differs from modeline! expected 18973.8us, measured 19027us +- 8.648us, difference 53.6us (6.2 sigma)
(kms_flip:1227) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 68.680939
current_received_ts = 68.680229
current_seq = 30
count = 0
seq_step = 10
(kms_flip:1227) DEBUG: name = vblank
last_ts = 68.680939
last_received_ts = 68.680229
last_seq = 30
current_ts = 68.871155
current_received_ts = 68.870422
current_seq = 40
count = 1
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 68.680939/30, current 68.871155/40: elapsed=190216.0us expected=190273.8us +- 951.4us, error 0.0%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 68.871155
last_received_ts = 68.870422
last_seq = 40
current_ts = 69.061356
current_received_ts = 69.060623
current_seq = 50
count = 2
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 68.871155/40, current 69.061356/50: elapsed=190200.0us expected=190273.8us +- 951.4us, error 0.0%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 69.061356
last_received_ts = 69.060623
last_seq = 50
current_ts = 69.251541
current_received_ts = 69.250824
current_seq = 60
count = 3
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 69.061356/50, current 69.251541/60: elapsed=190184.0us expected=190273.8us +- 951.4us, error 0.0%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 69.251541
last_received_ts = 69.250824
last_seq = 60
current_ts = 69.441757
current_received_ts = 69.441032
current_seq = 70
count = 4
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 69.251541/60, current 69.441757/70: elapsed=190214.0us expected=190273.8us +- 951.4us, error 0.0%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 69.441757
last_received_ts = 69.441032
last_seq = 70
current_ts = 69.631958
current_received_ts = 69.631226
current_seq = 80
count = 5
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 69.441757/70, current 69.631958/80: elapsed=190201.0us expected=190273.8us +- 951.4us, error 0.0%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 69.631958
last_received_ts = 69.631226
last_seq = 80
current_ts = 69.822098
current_received_ts = 69.821365
current_seq = 90
count = 6
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 69.631958/80, current 69.822098/90: elapsed=190141.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 69.822098
last_received_ts = 69.821365
last_seq = 90
current_ts = 70.012238
current_received_ts = 70.011513
current_seq = 100
count = 7
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 69.822098/90, current 70.012238/100: elapsed=190143.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.012238
last_received_ts = 70.011513
last_seq = 100
current_ts = 70.202370
current_received_ts = 70.201653
current_seq = 110
count = 8
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.012238/100, current 70.202370/110: elapsed=190126.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.202370
last_received_ts = 70.201653
last_seq = 110
current_ts = 70.392525
current_received_ts = 70.391792
current_seq = 120
count = 9
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.202370/110, current 70.392525/120: elapsed=190157.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.392525
last_received_ts = 70.391792
last_seq = 120
current_ts = 70.582664
current_received_ts = 70.581932
current_seq = 130
count = 10
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.392525/120, current 70.582664/130: elapsed=190142.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.582664
last_received_ts = 70.581932
last_seq = 130
current_ts = 70.772774
current_received_ts = 70.772049
current_seq = 140
count = 11
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.582664/130, current 70.772774/140: elapsed=190107.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.772774
last_received_ts = 70.772049
last_seq = 140
current_ts = 70.962845
current_received_ts = 70.962135
current_seq = 150
count = 12
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.772774/140, current 70.962845/150: elapsed=190076.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 70.962845
last_received_ts = 70.962135
last_seq = 150
current_ts = 71.152939
current_received_ts = 71.152222
current_seq = 160
count = 13
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 70.962845/150, current 71.152939/160: elapsed=190091.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 71.152939
last_received_ts = 71.152222
last_seq = 160
current_ts = 71.343048
current_received_ts = 71.342316
current_seq = 170
count = 14
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 71.152939/160, current 71.343048/170: elapsed=190107.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 71.343048
last_received_ts = 71.342316
last_seq = 170
current_ts = 71.533119
current_received_ts = 71.532410
current_seq = 180
count = 15
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 71.343048/170, current 71.533119/180: elapsed=190076.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 71.533119
last_received_ts = 71.532410
last_seq = 180
current_ts = 71.723206
current_received_ts = 71.722473
current_seq = 190
count = 16
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 71.533119/180, current 71.723206/190: elapsed=190085.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 71.723206
last_received_ts = 71.722473
last_seq = 190
current_ts = 71.913239
current_received_ts = 71.912521
current_seq = 200
count = 17
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 71.723206/190, current 71.913239/200: elapsed=190031.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 71.913239
last_received_ts = 71.912521
last_seq = 200
current_ts = 72.103287
current_received_ts = 72.102577
current_seq = 210
count = 18
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 71.913239/200, current 72.103287/210: elapsed=190047.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 72.103287
last_received_ts = 72.102577
last_seq = 210
current_ts = 72.293335
current_received_ts = 72.292618
current_seq = 220
count = 19
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 72.103287/210, current 72.293335/220: elapsed=190047.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 72.293335
last_received_ts = 72.292618
last_seq = 220
current_ts = 72.483398
current_received_ts = 72.482666
current_seq = 230
count = 20
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 72.293335/220, current 72.483398/230: elapsed=190064.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 72.483398
last_received_ts = 72.482666
last_seq = 230
current_ts = 72.673431
current_received_ts = 72.672699
current_seq = 240
count = 21
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 72.483398/230, current 72.673431/240: elapsed=190038.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 72.673431
last_received_ts = 72.672699
last_seq = 240
current_ts = 72.863426
current_received_ts = 72.862709
current_seq = 250
count = 22
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 72.673431/240, current 72.863426/250: elapsed=189993.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 72.863426
last_received_ts = 72.862709
last_seq = 250
current_ts = 73.053436
current_received_ts = 73.052719
current_seq = 260
count = 23
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 72.863426/250, current 73.053436/260: elapsed=190008.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 73.053436
last_received_ts = 73.052719
last_seq = 260
current_ts = 73.243462
current_received_ts = 73.242729
current_seq = 270
count = 24
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 73.053436/260, current 73.243462/270: elapsed=190024.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 73.243462
last_received_ts = 73.242729
last_seq = 270
current_ts = 73.433472
current_received_ts = 73.432732
current_seq = 280
count = 25
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 73.243462/270, current 73.433472/280: elapsed=190009.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 73.433472
last_received_ts = 73.432732
last_seq = 280
current_ts = 73.623474
current_received_ts = 73.622742
current_seq = 290
count = 26
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 73.433472/280, current 73.623474/290: elapsed=190008.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 73.623474
last_received_ts = 73.622742
last_seq = 290
current_ts = 73.813438
current_received_ts = 73.812721
current_seq = 300
count = 27
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 73.623474/290, current 73.813438/300: elapsed=189962.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 73.813438
last_received_ts = 73.812721
last_seq = 300
current_ts = 74.003426
current_received_ts = 74.002693
current_seq = 310
count = 28
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 73.813438/300, current 74.003426/310: elapsed=189990.0us expected=190273.8us +- 951.4us, error 0.1%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.003426
last_received_ts = 74.002693
last_seq = 310
current_ts = 74.193405
current_received_ts = 74.192673
current_seq = 320
count = 29
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.003426/310, current 74.193405/320: elapsed=189975.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.193405
last_received_ts = 74.192673
last_seq = 320
current_ts = 74.383362
current_received_ts = 74.382645
current_seq = 330
count = 30
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.193405/320, current 74.383362/330: elapsed=189959.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.383362
last_received_ts = 74.382645
last_seq = 330
current_ts = 74.573334
current_received_ts = 74.572624
current_seq = 340
count = 31
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.383362/330, current 74.573334/340: elapsed=189975.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.573334
last_received_ts = 74.572624
last_seq = 340
current_ts = 74.763306
current_received_ts = 74.762573
current_seq = 350
count = 32
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.573334/340, current 74.763306/350: elapsed=189969.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.763306
last_received_ts = 74.762573
last_seq = 350
current_ts = 74.953255
current_received_ts = 74.952522
current_seq = 360
count = 33
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.763306/350, current 74.953255/360: elapsed=189945.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 74.953255
last_received_ts = 74.952522
last_seq = 360
current_ts = 75.143196
current_received_ts = 75.142471
current_seq = 370
count = 34
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 74.953255/360, current 75.143196/370: elapsed=189944.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 75.143196
last_received_ts = 75.142471
last_seq = 370
current_ts = 75.333122
current_received_ts = 75.332413
current_seq = 380
count = 35
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 75.143196/370, current 75.333122/380: elapsed=189931.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 75.333122
last_received_ts = 75.332413
last_seq = 380
current_ts = 75.523087
current_received_ts = 75.522354
current_seq = 390
count = 36
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 75.333122/380, current 75.523087/390: elapsed=189960.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 75.523087
last_received_ts = 75.522354
last_seq = 390
current_ts = 75.713020
current_received_ts = 75.712288
current_seq = 400
count = 37
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 75.523087/390, current 75.713020/400: elapsed=189935.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 75.713020
last_received_ts = 75.712288
last_seq = 400
current_ts = 75.902924
current_received_ts = 75.902206
current_seq = 410
count = 38
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 75.713020/400, current 75.902924/410: elapsed=189903.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 75.902924
last_received_ts = 75.902206
last_seq = 410
current_ts = 76.092842
current_received_ts = 76.092133
current_seq = 420
count = 39
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 75.902924/410, current 76.092842/420: elapsed=189920.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 76.092842
last_received_ts = 76.092133
last_seq = 420
current_ts = 76.282761
current_received_ts = 76.282051
current_seq = 430
count = 40
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 76.092842/420, current 76.282761/430: elapsed=189919.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 76.282761
last_received_ts = 76.282051
last_seq = 430
current_ts = 76.472694
current_received_ts = 76.471977
current_seq = 440
count = 41
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 76.282761/430, current 76.472694/440: elapsed=189935.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 76.472694
last_received_ts = 76.471977
last_seq = 440
current_ts = 76.662613
current_received_ts = 76.661880
current_seq = 450
count = 42
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 76.472694/440, current 76.662613/450: elapsed=189914.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 76.662613
last_received_ts = 76.661880
last_seq = 450
current_ts = 76.852509
current_received_ts = 76.851776
current_seq = 460
count = 43
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 76.662613/450, current 76.852509/460: elapsed=189897.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 76.852509
last_received_ts = 76.851776
last_seq = 460
current_ts = 77.042389
current_received_ts = 77.041672
current_seq = 470
count = 44
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 76.852509/460, current 77.042389/470: elapsed=189881.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.042389
last_received_ts = 77.041672
last_seq = 470
current_ts = 77.232285
current_received_ts = 77.231575
current_seq = 480
count = 45
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.042389/470, current 77.232285/480: elapsed=189896.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.232285
last_received_ts = 77.231575
last_seq = 480
current_ts = 77.422180
current_received_ts = 77.421471
current_seq = 490
count = 46
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.232285/480, current 77.422180/490: elapsed=189897.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.422180
last_received_ts = 77.421471
last_seq = 490
current_ts = 77.612099
current_received_ts = 77.611359
current_seq = 500
count = 47
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.422180/490, current 77.612099/500: elapsed=189912.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.612099
last_received_ts = 77.611359
last_seq = 500
current_ts = 77.801956
current_received_ts = 77.801239
current_seq = 510
count = 48
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.612099/500, current 77.801956/510: elapsed=189862.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.801956
last_received_ts = 77.801239
last_seq = 510
current_ts = 77.991837
current_received_ts = 77.991119
current_seq = 520
count = 49
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.801956/510, current 77.991837/520: elapsed=189877.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 77.991837
last_received_ts = 77.991119
last_seq = 520
current_ts = 78.181709
current_received_ts = 78.181023
current_seq = 530
count = 50
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 77.991837/520, current 78.181709/530: elapsed=189877.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 78.181709
last_received_ts = 78.181023
last_seq = 530
current_ts = 78.371605
current_received_ts = 78.370872
current_seq = 540
count = 51
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 78.181709/530, current 78.371605/540: elapsed=189892.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 78.371605
last_received_ts = 78.370872
last_seq = 540
current_ts = 78.561478
current_received_ts = 78.560745
current_seq = 550
count = 52
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 78.371605/540, current 78.561478/550: elapsed=189876.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 78.561478
last_received_ts = 78.560745
last_seq = 550
current_ts = 78.751328
current_received_ts = 78.750610
current_seq = 560
count = 53
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 78.561478/550, current 78.751328/560: elapsed=189851.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 78.751328
last_received_ts = 78.750610
last_seq = 560
current_ts = 78.941193
current_received_ts = 78.940475
current_seq = 570
count = 54
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 78.751328/560, current 78.941193/570: elapsed=189859.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 78.941193
last_received_ts = 78.940475
last_seq = 570
current_ts = 79.131065
current_received_ts = 79.130333
current_seq = 580
count = 55
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 78.941193/570, current 79.131065/580: elapsed=189875.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 79.131065
last_received_ts = 79.130333
last_seq = 580
current_ts = 79.320923
current_received_ts = 79.320190
current_seq = 590
count = 56
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 79.131065/580, current 79.320923/590: elapsed=189859.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 79.320923
last_received_ts = 79.320190
last_seq = 590
current_ts = 79.510780
current_received_ts = 79.510048
current_seq = 600
count = 57
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 79.320923/590, current 79.510780/600: elapsed=189859.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 79.510780
last_received_ts = 79.510048
last_seq = 600
current_ts = 79.700623
current_received_ts = 79.699913
current_seq = 610
count = 58
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 79.510780/600, current 79.700623/610: elapsed=189839.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 79.700623
last_received_ts = 79.699913
last_seq = 610
current_ts = 79.890465
current_received_ts = 79.889748
current_seq = 620
count = 59
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 79.700623/610, current 79.890465/620: elapsed=189843.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 79.890465
last_received_ts = 79.889748
last_seq = 620
current_ts = 80.080322
current_received_ts = 80.079590
current_seq = 630
count = 60
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 79.890465/620, current 80.080322/630: elapsed=189860.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 80.080322
last_received_ts = 80.079590
last_seq = 630
current_ts = 80.270164
current_received_ts = 80.269440
current_seq = 640
count = 61
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 80.080322/630, current 80.270164/640: elapsed=189844.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 80.270164
last_received_ts = 80.269440
last_seq = 640
current_ts = 80.460014
current_received_ts = 80.459282
current_seq = 650
count = 62
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 80.270164/640, current 80.460014/650: elapsed=189844.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 80.460014
last_received_ts = 80.459282
last_seq = 650
current_ts = 80.649841
current_received_ts = 80.649124
current_seq = 660
count = 63
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 80.460014/650, current 80.649841/660: elapsed=189828.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 80.649841
last_received_ts = 80.649124
last_seq = 660
current_ts = 80.839668
current_received_ts = 80.838966
current_seq = 670
count = 64
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 80.649841/660, current 80.839668/670: elapsed=189830.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 80.839668
last_received_ts = 80.838966
last_seq = 670
current_ts = 81.029503
current_received_ts = 81.028786
current_seq = 680
count = 65
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 80.839668/670, current 81.029503/680: elapsed=189831.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.029503
last_received_ts = 81.028786
last_seq = 680
current_ts = 81.219330
current_received_ts = 81.218620
current_seq = 690
count = 66
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.029503/680, current 81.219330/690: elapsed=189830.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.219330
last_received_ts = 81.218620
last_seq = 690
current_ts = 81.409180
current_received_ts = 81.408447
current_seq = 700
count = 67
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.219330/690, current 81.409180/700: elapsed=189847.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.409180
last_received_ts = 81.408447
last_seq = 700
current_ts = 81.598991
current_received_ts = 81.598282
current_seq = 710
count = 68
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.409180/700, current 81.598991/710: elapsed=189816.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.598991
last_received_ts = 81.598282
last_seq = 710
current_ts = 81.788818
current_received_ts = 81.788101
current_seq = 720
count = 69
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.598991/710, current 81.788818/720: elapsed=189821.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.788818
last_received_ts = 81.788101
last_seq = 720
current_ts = 81.978638
current_received_ts = 81.977936
current_seq = 730
count = 70
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.788818/720, current 81.978638/730: elapsed=189821.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 81.978638
last_received_ts = 81.977936
last_seq = 730
current_ts = 82.168472
current_received_ts = 82.167740
current_seq = 740
count = 71
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 81.978638/730, current 82.168472/740: elapsed=189833.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 82.168472
last_received_ts = 82.167740
last_seq = 740
current_ts = 82.358292
current_received_ts = 82.357559
current_seq = 750
count = 72
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 82.168472/740, current 82.358292/750: elapsed=189819.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 82.358292
last_received_ts = 82.357559
last_seq = 750
current_ts = 82.548088
current_received_ts = 82.547379
current_seq = 760
count = 73
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 82.358292/750, current 82.548088/760: elapsed=189803.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 82.548088
last_received_ts = 82.547379
last_seq = 760
current_ts = 82.737923
current_received_ts = 82.737206
current_seq = 770
count = 74
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 82.548088/760, current 82.737923/770: elapsed=189830.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 82.737923
last_received_ts = 82.737206
last_seq = 770
current_ts = 82.927727
current_received_ts = 82.926994
current_seq = 780
count = 75
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 82.737923/770, current 82.927727/780: elapsed=189809.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 82.927727
last_received_ts = 82.926994
last_seq = 780
current_ts = 83.117531
current_received_ts = 83.116821
current_seq = 790
count = 76
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 82.927727/780, current 83.117531/790: elapsed=189800.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 83.117531
last_received_ts = 83.116821
last_seq = 790
current_ts = 83.307343
current_received_ts = 83.306625
current_seq = 800
count = 77
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 83.117531/790, current 83.307343/800: elapsed=189812.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: name = vblank
last_ts = 83.307343
last_received_ts = 83.306625
last_seq = 800
current_ts = 83.497154
current_received_ts = 83.496445
current_seq = 810
count = 78
seq_step = 10
(kms_flip:1227) DEBUG: vblank ts/seq: last 83.307343/800, current 83.497154/810: elapsed=189814.0us expected=190273.8us +- 951.4us, error 0.2%
(kms_flip:1227) DEBUG: expected 788, counted 790, encoder type 2
(kms_flip:1227) DEBUG: dropped frames, expected 788, counted 790, encoder type 2
(kms_flip:1227) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1227) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1227) igt_core-INFO: Stack trace:
(kms_flip:1227) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1227) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1227) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1227) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:1227) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1227) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1227) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1227) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (18.478s)
Subtest blocking-wf_vblank: FAIL (27.527s)
(kms_flip:1227) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2234:
(kms_flip:1227) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:1227) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:1227) igt_kms-CRITICAL: DPMS property not found on 137
Dmesg
<6> [67.851246] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1
<7> [67.851715] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:166]
<7> [67.851780] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167]
<7> [67.884980] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [67.885100] 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> [67.885450] 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> [67.885853] 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> [67.886225] 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> [67.886606] 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> [67.886962] 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> [67.887316] 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> [67.887682] 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> [67.888033] 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> [67.888380] 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> [67.888737] 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> [67.889012] 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> [67.889281] 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> [67.889572] 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> [67.889847] 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> [67.890115] 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> [67.890383] 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> [67.890679] 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> [67.890957] 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> [67.891230] 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> [67.891508] 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> [67.891692] 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> [67.891815] 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> [67.891936] 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> [67.892059] 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> [67.892180] 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> [67.892302] 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> [67.892422] 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> [67.892553] 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> [67.892674] 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> [67.892796] 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> [67.892916] 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> [67.893036] 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> [67.893157] 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> [67.893278] 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> [67.893400] 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> [67.893531] 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> [67.893660] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [67.893788] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [67.893927] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [67.894045] 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> [67.894183] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [67.894298] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [67.894413] 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> [67.894539] 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> [67.894670] 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> [67.894784] 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> [67.894900] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [67.895026] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [67.895165] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [67.895293] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [67.895422] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [67.895562] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [67.895687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [67.895811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [67.895934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [67.896058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [67.896181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [67.896340] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [67.896476] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [67.908235] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [67.929190] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [67.929766] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [67.935218] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [67.935797] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [67.936300] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [67.936839] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [67.937362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [67.937825] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [67.938279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [67.938782] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [67.939242] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [67.939943] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [67.940570] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [67.941674] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [67.942046] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [67.942103] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:137:HDMI-A-1]
<7> [67.942222] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:137:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [67.942784] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [67.943103] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [67.943481] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:136:DDI B/PHY B] [CRTC:134:pipe B]
<7> [67.943811] 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> [67.944171] 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> [67.944549] 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> [67.944900] 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> [67.945253] 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> [67.945637] 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> [67.945925] 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> [67.946185] 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> [67.946465] 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> [67.946726] 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> [67.946987] 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> [67.947248] 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> [67.947531] 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> [67.947798] 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> [67.948062] 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> [67.948341] 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> [67.948601] 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> [67.948821] 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> [67.949007] 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> [67.949191] 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> [67.949388] 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> [67.949570] 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> [67.949755] 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> [67.949939] 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> [67.950121] 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> [67.950312] 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> [67.950496] 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> [67.950679] 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> [67.950861] 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> [67.951045] 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> [67.951235] 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> [67.951439] 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> [67.951627] 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> [67.951811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state
<7> [67.951939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [67.952064] 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> [67.952190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [67.952321] 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> [67.952448] 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> [67.952575] 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> [67.952699] 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> [67.952824] 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> [67.952950] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [67.953088] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [67.953208] 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> [67.953357] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [67.953476] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [67.953597] 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> [67.953718] 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> [67.953840] 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> [67.953961] 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> [67.954088] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [67.954220] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [67.954361] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [67.954501] 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> [67.954631] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [67.954773] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [67.954898] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [67.955028] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [67.955154] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [67.955283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [67.955407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [67.955533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [67.955657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [67.955783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [67.955905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [67.956030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [67.956156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [67.956287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [67.956412] 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> [67.956536] 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> [67.956662] 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> [67.956787] 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> [67.956912] 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> [67.957040] 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> [67.957162] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [67.957291] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [67.957414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [67.957536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [67.957658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [67.957779] 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> [67.957900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [67.958028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [67.958150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [67.958276] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.958399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.958519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.958639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [67.958762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [67.958884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.959005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.959126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [67.959248] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [67.959381] 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> [67.959505] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [67.959626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [67.959747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [67.959868] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [67.959987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [67.960114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [67.960236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [67.960843] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [67.961055] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [67.961181] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [67.961313] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [67.961433] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [67.961554] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [67.961702] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [67.961859] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [67.961985] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [67.962411] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [67.967766] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [67.967932] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [67.987787] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [67.988106] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [68.925709] pcieport 0000:00:1b.0: AER: Correctable error message received from 0000:01:00.0
<4> [68.925792] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [68.925803] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [68.925813] nvme 0000:01:00.0: [ 0] RxErr
<6> [86.329732] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL
<6> [86.330320] [IGT] kms_flip: finished subtest blocking-wf_vblank, FAIL
<7> [86.335776] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:167] from all active usage due to RMFB ioctl
<7> [86.336003] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:167] is removed
<7> [86.336140] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [86.336405] 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> [86.336628] 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> [86.336878] 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> [86.337114] 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> [86.337372] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [86.337631] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [86.362894] [IGT] kms_flip: exiting, ret=98
<7> [86.371478] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:137:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [86.371634] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [86.371753] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [86.371893] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:136:DDI B/PHY B] [CRTC:82:pipe A]
<7> [86.372013] 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> [86.372143] 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> [86.372271] 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> [86.372413] 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> [86.372540] 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> [86.372665] 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> [86.372792] 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> [86.372920] 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> [86.373047] 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> [86.373170] 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> [86.373297] 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> [86.373442] 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> [86.373568] 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> [86.373693] 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> [86.373815] 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> [86.373938] 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> [86.374061] 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> [86.374189] 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> [86.374311] 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> [86.374449] 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> [86.374570] 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> [86.374692] 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> [86.374814] 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> [86.374936] 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> [86.375057] 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> [86.375182] 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> [86.375305] 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> [86.375442] 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> [86.375563] 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> [86.375684] 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> [86.375805] 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> [86.375926] 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> [86.376046] 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> [86.376169] 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> [86.376289] 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> [86.376422] 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> [86.376545] 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> [86.376665] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [86.376786] 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> [86.376906] 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> [86.377028] 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> [86.377155] 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> [86.377278] 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> [86.377447] 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> [86.377575] 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> [86.377700] 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> [86.377823] 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> [86.377948] 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> [86.378073] 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> [86.378195] 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> [86.378319] 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> [86.378494] 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> [86.378618] 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> [86.378740] 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> [86.378862] 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> [86.378985] 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> [86.379114] 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> [86.379237] 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> [86.379369] 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> [86.379490] 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> [86.379612] 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> [86.379733] 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> [86.379855] 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> [86.379975] 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> [86.380098] 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> [86.380218] 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> [86.380361] 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> [86.380481] 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> [86.380602] 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> [86.380723] 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> [86.380845] 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> [86.380966] 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> [86.381088] 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> [86.381211] 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> [86.381344] 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> [86.381473] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [86.381598] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [86.381744] 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> [86.381862] 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> [86.381990] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [86.382107] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [86.382223] 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> [86.382347] 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> [86.382462] 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> [86.382577] 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> [86.382693] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [86.382809] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [86.382936] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [86.383065] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [86.383204] 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> [86.383343] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [86.383472] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [86.383600] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [86.383727] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [86.383852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [86.383979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [86.384105] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [86.384229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [86.384362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [86.384486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [86.384608] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [86.384731] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [86.384853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [86.384978] 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> [86.385105] 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> [86.385233] 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> [86.385521] 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> [86.385649] 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> [86.385774] 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> [86.385900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [86.386024] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [86.386146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [86.386269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [86.386406] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [86.386527] 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> [86.386650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [86.386772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [86.386894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [86.387017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.387138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.387259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.387395] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [86.387518] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [86.387639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.387761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.387882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [86.388004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [86.388128] 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> [86.388255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [86.388393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [86.388518] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [86.388641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [86.388764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [86.388887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [86.389009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [86.389131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [86.389251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [86.389387] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [86.389510] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [86.389631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [86.389754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [86.389874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [86.390387] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [86.393291] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [86.393494] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [86.398225] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [86.398411] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [86.398549] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [86.398673] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:146:DDI TC1/PHY C]
<7> [86.398795] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:150:DDI TC2/PHY D]
<7> [86.398917] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:152:DP-MST A]
<7> [86.399036] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DP-MST B]
<7> [86.399219] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [86.399357] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [86.399841] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [86.404996] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [86.405168] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [86.405602] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [86.405730] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [86.424734] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:137:HDMI-A-1]
<7> [86.425084] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [86.425844] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [86.426231] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [86.426889] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7> [86.444391] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [86.444904] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [86.445435] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
Created at 2024-10-18 18:20:34