Results for igt@kms_flip@2x-flip-vs-expired-vblank-interruptible@ad-dp2-hdmi-a3

Result: Fail

i915_display_info10 igt_runner10 results10.json results10-xe-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 2.28 seconds
Hostname 
shard-bmg-7
Igt-Version 
IGT-Version: 2.4-ge70db143b (x86_64) (Linux: 7.0.0-lgci-xe-xe-4932-15ccea1aede9d776f-debug+ x86_64)
Out 
Starting dynamic subtest: AD-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AD-DP2-HDMI-A3: FAIL (2.277s)
Err 
Starting dynamic subtest: AD-DP2-HDMI-A3
(kms_flip:4463) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4463) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4463) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest AD-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:4463) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0)
(kms_flip:4463) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4463) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4463) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:4463) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4463) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0)
(kms_flip:4463) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4463) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4463) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:4463) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4463) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) intel_allocator_simple-DEBUG: <ial: 0x5f3625b148f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:4463) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:4463) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
(kms_flip:4463) DEBUG: No stale events found
(kms_flip:4463) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4463) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4463) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4463) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4463) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 110.863342
current_received_ts = 110.862831
current_seq = 3731
count = 0
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 241us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.863342
last_received_ts = 110.862831
last_seq = 3731
current_ts = 110.880005
current_received_ts = 110.879501
current_seq = 3732
count = 1
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.880005
last_received_ts = 110.879501
last_seq = 3732
current_ts = 110.896667
current_received_ts = 110.896255
current_seq = 3733
count = 2
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.896667
last_received_ts = 110.896255
last_seq = 3733
current_ts = 110.913338
current_received_ts = 110.912918
current_seq = 3734
count = 3
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.913338
last_received_ts = 110.912918
last_seq = 3734
current_ts = 110.930000
current_received_ts = 110.929581
current_seq = 3735
count = 4
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 42us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.930000
last_received_ts = 110.929581
last_seq = 3735
current_ts = 110.946671
current_received_ts = 110.946251
current_seq = 3736
count = 5
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.946671
last_received_ts = 110.946251
last_seq = 3736
current_ts = 110.963333
current_received_ts = 110.962914
current_seq = 3737
count = 6
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.963333
last_received_ts = 110.962914
last_seq = 3737
current_ts = 110.980011
current_received_ts = 110.979591
current_seq = 3738
count = 7
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 43us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.980011
last_received_ts = 110.979591
last_seq = 3738
current_ts = 110.996674
current_received_ts = 110.996254
current_seq = 3739
count = 8
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 110.996674
last_received_ts = 110.996254
last_seq = 3739
current_ts = 111.013336
current_received_ts = 111.012917
current_seq = 3740
count = 9
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.013336
last_received_ts = 111.012917
last_seq = 3740
current_ts = 111.030006
current_received_ts = 111.029587
current_seq = 3741
count = 10
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 36us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.030006
last_received_ts = 111.029587
last_seq = 3741
current_ts = 111.046669
current_received_ts = 111.046249
current_seq = 3742
count = 11
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.046669
last_received_ts = 111.046249
last_seq = 3742
current_ts = 111.063339
current_received_ts = 111.062920
current_seq = 3743
count = 12
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.063339
last_received_ts = 111.062920
last_seq = 3743
current_ts = 111.080017
current_received_ts = 111.079620
current_seq = 3744
count = 13
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 37us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.080017
last_received_ts = 111.079620
last_seq = 3744
current_ts = 111.096664
current_received_ts = 111.096245
current_seq = 3745
count = 14
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.096664
last_received_ts = 111.096245
last_seq = 3745
current_ts = 111.113335
current_received_ts = 111.112915
current_seq = 3746
count = 15
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.113335
last_received_ts = 111.112915
last_seq = 3746
current_ts = 111.130005
current_received_ts = 111.129585
current_seq = 3747
count = 16
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 43us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.130005
last_received_ts = 111.129585
last_seq = 3747
current_ts = 111.146667
current_received_ts = 111.146248
current_seq = 3748
count = 17
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.146667
last_received_ts = 111.146248
last_seq = 3748
current_ts = 111.163338
current_received_ts = 111.162903
current_seq = 3749
count = 18
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.163338
last_received_ts = 111.162903
last_seq = 3749
current_ts = 111.180008
current_received_ts = 111.179573
current_seq = 3750
count = 19
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.180008
last_received_ts = 111.179573
last_seq = 3750
current_ts = 111.196678
current_received_ts = 111.196243
current_seq = 3751
count = 20
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.196678
last_received_ts = 111.196243
last_seq = 3751
current_ts = 111.213341
current_received_ts = 111.212906
current_seq = 3752
count = 21
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.213341
last_received_ts = 111.212906
last_seq = 3752
current_ts = 111.230003
current_received_ts = 111.229660
current_seq = 3753
count = 22
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 36us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.230003
last_received_ts = 111.229660
last_seq = 3753
current_ts = 111.246674
current_received_ts = 111.246254
current_seq = 3754
count = 23
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.246674
last_received_ts = 111.246254
last_seq = 3754
current_ts = 111.263336
current_received_ts = 111.262917
current_seq = 3755
count = 24
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.263336
last_received_ts = 111.262917
last_seq = 3755
current_ts = 111.280006
current_received_ts = 111.279587
current_seq = 3756
count = 25
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 36us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.280006
last_received_ts = 111.279587
last_seq = 3756
current_ts = 111.296669
current_received_ts = 111.296295
current_seq = 3757
count = 26
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.296669
last_received_ts = 111.296295
last_seq = 3757
current_ts = 111.313339
current_received_ts = 111.312920
current_seq = 3758
count = 27
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.313339
last_received_ts = 111.312920
last_seq = 3758
current_ts = 111.330009
current_received_ts = 111.329590
current_seq = 3759
count = 28
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 43us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.330009
last_received_ts = 111.329590
last_seq = 3759
current_ts = 111.346680
current_received_ts = 111.346260
current_seq = 3760
count = 29
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.346680
last_received_ts = 111.346260
last_seq = 3760
current_ts = 111.363342
current_received_ts = 111.362923
current_seq = 3761
count = 30
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.363342
last_received_ts = 111.362923
last_seq = 3761
current_ts = 111.380013
current_received_ts = 111.379585
current_seq = 3762
count = 31
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.380013
last_received_ts = 111.379585
last_seq = 3762
current_ts = 111.396683
current_received_ts = 111.396255
current_seq = 3763
count = 32
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.396683
last_received_ts = 111.396255
last_seq = 3763
current_ts = 111.413345
current_received_ts = 111.412926
current_seq = 3764
count = 33
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.413345
last_received_ts = 111.412926
last_seq = 3764
current_ts = 111.430008
current_received_ts = 111.429588
current_seq = 3765
count = 34
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 38us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.430008
last_received_ts = 111.429588
last_seq = 3765
current_ts = 111.446678
current_received_ts = 111.446304
current_seq = 3766
count = 35
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.446678
last_received_ts = 111.446304
last_seq = 3766
current_ts = 111.463341
current_received_ts = 111.462914
current_seq = 3767
count = 36
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.463341
last_received_ts = 111.462914
last_seq = 3767
current_ts = 111.480011
current_received_ts = 111.479576
current_seq = 3768
count = 37
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.480011
last_received_ts = 111.479576
last_seq = 3768
current_ts = 111.496689
current_received_ts = 111.496246
current_seq = 3769
count = 38
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.496689
last_received_ts = 111.496246
last_seq = 3769
current_ts = 111.513344
current_received_ts = 111.512909
current_seq = 3770
count = 39
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.513344
last_received_ts = 111.512909
last_seq = 3770
current_ts = 111.530022
current_received_ts = 111.529633
current_seq = 3771
count = 40
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.530022
last_received_ts = 111.529633
last_seq = 3771
current_ts = 111.546677
current_received_ts = 111.546257
current_seq = 3772
count = 41
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.546677
last_received_ts = 111.546257
last_seq = 3772
current_ts = 111.563347
current_received_ts = 111.562927
current_seq = 3773
count = 42
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.563347
last_received_ts = 111.562927
last_seq = 3773
current_ts = 111.580009
current_received_ts = 111.579590
current_seq = 3774
count = 43
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.580009
last_received_ts = 111.579590
last_seq = 3774
current_ts = 111.596687
current_received_ts = 111.596268
current_seq = 3775
count = 44
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.596687
last_received_ts = 111.596268
last_seq = 3775
current_ts = 111.613342
current_received_ts = 111.612923
current_seq = 3776
count = 45
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.613342
last_received_ts = 111.612923
last_seq = 3776
current_ts = 111.630013
current_received_ts = 111.629593
current_seq = 3777
count = 46
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 71us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.630013
last_received_ts = 111.629593
last_seq = 3777
current_ts = 111.646690
current_received_ts = 111.646332
current_seq = 3778
count = 47
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 36us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.646690
last_received_ts = 111.646332
last_seq = 3778
current_ts = 111.663345
current_received_ts = 111.662910
current_seq = 3779
count = 48
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 35us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.663345
last_received_ts = 111.662910
last_seq = 3779
current_ts = 111.680023
current_received_ts = 111.679543
current_seq = 3780
count = 49
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.680023
last_received_ts = 111.679543
last_seq = 3780
current_ts = 111.696686
current_received_ts = 111.696243
current_seq = 3781
count = 50
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.696686
last_received_ts = 111.696243
last_seq = 3781
current_ts = 111.713356
current_received_ts = 111.712868
current_seq = 3782
count = 51
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.713356
last_received_ts = 111.712868
last_seq = 3782
current_ts = 111.730019
current_received_ts = 111.729576
current_seq = 3783
count = 52
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.730019
last_received_ts = 111.729576
last_seq = 3783
current_ts = 111.746681
current_received_ts = 111.746246
current_seq = 3784
count = 53
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 27us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.746681
last_received_ts = 111.746246
last_seq = 3784
current_ts = 111.763351
current_received_ts = 111.762871
current_seq = 3785
count = 54
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.763351
last_received_ts = 111.762871
last_seq = 3785
current_ts = 111.780022
current_received_ts = 111.779533
current_seq = 3786
count = 55
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.780022
last_received_ts = 111.779533
last_seq = 3786
current_ts = 111.796684
current_received_ts = 111.796204
current_seq = 3787
count = 56
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.796684
last_received_ts = 111.796204
last_seq = 3787
current_ts = 111.813354
current_received_ts = 111.812866
current_seq = 3788
count = 57
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.813354
last_received_ts = 111.812866
last_seq = 3788
current_ts = 111.830025
current_received_ts = 111.829536
current_seq = 3789
count = 58
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.830025
last_received_ts = 111.829536
last_seq = 3789
current_ts = 111.846687
current_received_ts = 111.846207
current_seq = 3790
count = 59
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.846687
last_received_ts = 111.846207
last_seq = 3790
current_ts = 111.863358
current_received_ts = 111.862869
current_seq = 3791
count = 60
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.863358
last_received_ts = 111.862869
last_seq = 3791
current_ts = 111.880020
current_received_ts = 111.879539
current_seq = 3792
count = 61
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.880020
last_received_ts = 111.879539
last_seq = 3792
current_ts = 111.896690
current_received_ts = 111.896202
current_seq = 3793
count = 62
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.896690
last_received_ts = 111.896202
last_seq = 3793
current_ts = 111.913361
current_received_ts = 111.912857
current_seq = 3794
count = 63
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.913361
last_received_ts = 111.912857
last_seq = 3794
current_ts = 111.930023
current_received_ts = 111.929535
current_seq = 3795
count = 64
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 23us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.930023
last_received_ts = 111.929535
last_seq = 3795
current_ts = 111.946693
current_received_ts = 111.946190
current_seq = 3796
count = 65
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.946693
last_received_ts = 111.946190
last_seq = 3796
current_ts = 111.963356
current_received_ts = 111.962875
current_seq = 3797
count = 66
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.963356
last_received_ts = 111.962875
last_seq = 3797
current_ts = 111.980026
current_received_ts = 111.979538
current_seq = 3798
count = 67
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.980026
last_received_ts = 111.979538
last_seq = 3798
current_ts = 111.996689
current_received_ts = 111.996208
current_seq = 3799
count = 68
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 111.996689
last_received_ts = 111.996208
last_seq = 3799
current_ts = 112.013359
current_received_ts = 112.012878
current_seq = 3800
count = 69
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.013359
last_received_ts = 112.012878
last_seq = 3800
current_ts = 112.030022
current_received_ts = 112.029549
current_seq = 3801
count = 70
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.030022
last_received_ts = 112.029549
last_seq = 3801
current_ts = 112.046692
current_received_ts = 112.046211
current_seq = 3802
count = 71
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.046692
last_received_ts = 112.046211
last_seq = 3802
current_ts = 112.063362
current_received_ts = 112.062859
current_seq = 3803
count = 72
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.063362
last_received_ts = 112.062859
last_seq = 3803
current_ts = 112.080025
current_received_ts = 112.079544
current_seq = 3804
count = 73
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.080025
last_received_ts = 112.079544
last_seq = 3804
current_ts = 112.096695
current_received_ts = 112.096207
current_seq = 3805
count = 74
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.096695
last_received_ts = 112.096207
last_seq = 3805
current_ts = 112.113365
current_received_ts = 112.112862
current_seq = 3806
count = 75
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.113365
last_received_ts = 112.112862
last_seq = 3806
current_ts = 112.130028
current_received_ts = 112.129539
current_seq = 3807
count = 76
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.130028
last_received_ts = 112.129539
last_seq = 3807
current_ts = 112.146698
current_received_ts = 112.146210
current_seq = 3808
count = 77
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.146698
last_received_ts = 112.146210
last_seq = 3808
current_ts = 112.163361
current_received_ts = 112.162872
current_seq = 3809
count = 78
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.163361
last_received_ts = 112.162872
last_seq = 3809
current_ts = 112.180031
current_received_ts = 112.179543
current_seq = 3810
count = 79
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.180031
last_received_ts = 112.179543
last_seq = 3810
current_ts = 112.196693
current_received_ts = 112.196198
current_seq = 3811
count = 80
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.196693
last_received_ts = 112.196198
last_seq = 3811
current_ts = 112.213364
current_received_ts = 112.212875
current_seq = 3812
count = 81
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.213364
last_received_ts = 112.212875
last_seq = 3812
current_ts = 112.230034
current_received_ts = 112.229530
current_seq = 3813
count = 82
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.230034
last_received_ts = 112.229530
last_seq = 3813
current_ts = 112.246696
current_received_ts = 112.246208
current_seq = 3814
count = 83
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.246696
last_received_ts = 112.246208
last_seq = 3814
current_ts = 112.263367
current_received_ts = 112.262863
current_seq = 3815
count = 84
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.263367
last_received_ts = 112.262863
last_seq = 3815
current_ts = 112.280029
current_received_ts = 112.279549
current_seq = 3816
count = 85
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.280029
last_received_ts = 112.279549
last_seq = 3816
current_ts = 112.296700
current_received_ts = 112.296211
current_seq = 3817
count = 86
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.296700
last_received_ts = 112.296211
last_seq = 3817
current_ts = 112.313370
current_received_ts = 112.312866
current_seq = 3818
count = 87
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.313370
last_received_ts = 112.312866
last_seq = 3818
current_ts = 112.330032
current_received_ts = 112.329536
current_seq = 3819
count = 88
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.330032
last_received_ts = 112.329536
last_seq = 3819
current_ts = 112.346703
current_received_ts = 112.346214
current_seq = 3820
count = 89
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 24us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.346703
last_received_ts = 112.346214
last_seq = 3820
current_ts = 112.363365
current_received_ts = 112.362900
current_seq = 3821
count = 90
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.363365
last_received_ts = 112.362900
last_seq = 3821
current_ts = 112.380035
current_received_ts = 112.379547
current_seq = 3822
count = 91
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 26us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.380035
last_received_ts = 112.379547
last_seq = 3822
current_ts = 112.396698
current_received_ts = 112.396217
current_seq = 3823
count = 92
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.396698
last_received_ts = 112.396217
last_seq = 3823
current_ts = 112.413368
current_received_ts = 112.412872
current_seq = 3824
count = 93
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.413368
last_received_ts = 112.412872
last_seq = 3824
current_ts = 112.430038
current_received_ts = 112.429550
current_seq = 3825
count = 94
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.430038
last_received_ts = 112.429550
last_seq = 3825
current_ts = 112.446701
current_received_ts = 112.446220
current_seq = 3826
count = 95
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.446701
last_received_ts = 112.446220
last_seq = 3826
current_ts = 112.463371
current_received_ts = 112.462883
current_seq = 3827
count = 96
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 25us
(kms_flip:4463) DEBUG: name = flip
last_ts = 112.463371
last_received_ts = 112.462883
last_seq = 3827
current_ts = 112.480034
current_received_ts = 112.479553
current_seq = 3828
count = 97
seq_step = 1
(kms_flip:4463) DEBUG: Vblank took 1356us
(kms_flip:4463) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4463) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4463) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:4463) igt_core-INFO: Stack trace:
(kms_flip:4463) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4463) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:4463) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:4463) igt_core-INFO:   #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
(kms_flip:4463) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4463) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4463) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4463) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AD-DP2-HDMI-A3: FAIL (2.277s)
Dmesg
<6> [110.645546] [IGT] kms_flip: starting dynamic subtest AD-DP2-HDMI-A3
<7> [110.646269] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:556]
<7> [110.646600] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:559]
<7> [110.690816] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [110.690896] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [110.691031] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [110.691137] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [110.691237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [110.691337] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [110.691463] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [110.691568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [110.691669] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [110.691767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [110.691864] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [110.691961] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [110.692059] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [110.692156] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [110.692252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [110.692349] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [110.692459] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [110.692558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [110.692661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [110.692761] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [110.692859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [110.692966] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [110.693063] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [110.693167] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [110.693263] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [110.693366] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [110.693460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [110.693556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [110.693652] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [110.693747] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [110.693842] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [110.693940] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [110.694042] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [110.694142] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [110.694242] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [110.694339] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [110.694467] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [110.694563] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [110.694659] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [110.694754] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [110.694851] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [110.694948] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [110.695074] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [110.695161] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [110.695250] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [110.695360] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [110.695440] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (1989 - 2048) -> (4037 - 4096), size 59 -> 59
<7> [110.695518] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [110.695649] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [110.695755] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [110.695856] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [110.695954] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [110.696052] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [110.696148] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [110.696245] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [110.696742] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [110.721322] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [110.722137] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [110.739630] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [110.742267] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [110.744954] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [110.745592] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [110.745683] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [110.745997] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [110.746407] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [110.746777] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [110.747112] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [110.747486] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [110.748040] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [110.748396] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [110.748698] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [110.748977] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [110.749263] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [110.749695] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [110.749947] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [110.750195] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [110.750470] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [110.750723] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [110.750957] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [110.752808] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [110.753080] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [110.768494] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [110.769192] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [110.770103] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [110.770896] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [110.772215] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [110.772487] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [110.772921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [110.773338] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [110.773803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [110.774186] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [110.774585] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [110.774930] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [110.775267] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [110.775612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [110.775919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [110.776198] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [110.776507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [110.776777] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [110.777039] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [110.777296] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [110.777557] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [110.777795] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [110.778023] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [110.778266] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [110.778508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [110.778723] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [110.778925] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [110.779124] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [110.779431] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [110.779733] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [110.780034] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [110.780332] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [110.780697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [110.781011] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [110.781308] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [110.781650] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [110.781932] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [110.782199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [110.782490] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [110.782740] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [110.782982] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [110.783217] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [110.783463] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [110.783684] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [110.783897] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [110.784113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [110.784322] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [110.784545] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.784731] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [110.784735] xe 0000:03:00.0: colorspace: RGB
<7> [110.784739] xe 0000:03:00.0: scan mode: Underscan
<7> [110.784742] xe 0000:03:00.0: colorimetry: No Data
<7> [110.784745] xe 0000:03:00.0: picture aspect: No Data
<7> [110.784748] xe 0000:03:00.0: active aspect: Same as Picture
<7> [110.784751] xe 0000:03:00.0: itc: No Data
<7> [110.784754] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [110.784758] xe 0000:03:00.0: quantization range: Full
<7> [110.784761] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [110.784764] xe 0000:03:00.0: video code: 0
<7> [110.784767] xe 0000:03:00.0: ycc quantization range: Full
<7> [110.784772] xe 0000:03:00.0: hdmi content type: Graphics
<7> [110.784775] xe 0000:03:00.0: pixel repeat: 0
<7> [110.784778] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [110.784781] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.784962] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [110.785139] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.785312] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [110.785316] xe 0000:03:00.0: vendor: Intel
<7> [110.785319] xe 0000:03:00.0: product: Discrete gfx
<7> [110.785322] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [110.785325] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.785524] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [110.785690] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.785848] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [110.785851] xe 0000:03:00.0: empty frame
<7> [110.785853] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.786010] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [110.786170] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe B] releasing TC PLL 3
<7> [110.786381] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [110.786519] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [110.786680] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [110.786808] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [110.786997] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: no [modeset]
<7> [110.787147] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [NOFB], visible: no
<7> [110.787293] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [110.787454] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [110.787596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [110.787738] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [110.787879] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [110.788618] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:387:pipe B]
<7> [110.818597] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [110.819205] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [110.831790] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [110.836742] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:387:pipe B]
<7> [110.839312] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [110.840054] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [110.840438] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [110.840754] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [110.841063] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [110.841389] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [110.841676] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [110.842046] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [110.842302] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [110.842585] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [110.842833] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [110.843067] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [110.843518] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [110.843738] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [110.843955] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [110.844159] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [110.844376] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [110.844446] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [110.844582] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [110.845074] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [110.845491] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [110.845664] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [110.846148] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [110.846558] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [110.846680] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [110.846790] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [110.847155] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [110.847440] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [110.847460] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [110.847521] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [110.847621] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [110.847719] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:505:pipe D]
<7> [110.847814] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [110.847906] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [110.847997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [110.848087] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [110.848178] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [110.848272] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [110.848371] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [110.848471] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [110.848567] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [110.848655] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [110.848752] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [110.848842] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [110.848929] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [110.849015] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [110.849102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [110.849189] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [110.849278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [110.849370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [110.849465] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [110.849560] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [110.849655] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [110.849750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [110.849853] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [110.849947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [110.850041] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [110.850137] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [110.850236] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [110.850331] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [110.850440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [110.850534] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [110.850629] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [110.850729] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [110.850828] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [110.850932] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [110.851033] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [110.851133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in dpll_hw_state
<7> [110.851232] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.851328] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [110.851436] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [110.851532] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [110.851627] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [110.851723] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [110.851818] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [110.851912] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [110.852006] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [110.852101] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [110.852194] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [110.852288] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [110.852412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [110.852509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [110.852606] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [110.852709] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [110.852812] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [110.852912] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.853008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [110.853105] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [110.853204] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [110.853301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [110.853410] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [110.853507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [110.853603] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [110.853698] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [110.853793] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [110.853887] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [110.853983] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [110.854077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [110.854172] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [110.854266] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [110.854364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [110.854461] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [110.854556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [110.854651] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [110.854746] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [110.854840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [110.854935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [110.855032] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [110.855127] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [110.855227] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.855321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.855422] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [110.855424] xe 0000:03:00.0: colorspace: RGB
<7> [110.855426] xe 0000:03:00.0: scan mode: Underscan
<7> [110.855428] xe 0000:03:00.0: colorimetry: No Data
<7> [110.855429] xe 0000:03:00.0: picture aspect: No Data
<7> [110.855430] xe 0000:03:00.0: active aspect: Same as Picture
<7> [110.855432] xe 0000:03:00.0: itc: No Data
<7> [110.855433] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [110.855434] xe 0000:03:00.0: quantization range: Full
<7> [110.855435] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [110.855437] xe 0000:03:00.0: video code: 0
<7> [110.855438] xe 0000:03:00.0: ycc quantization range: Full
<7> [110.855439] xe 0000:03:00.0: hdmi content type: Graphics
<7> [110.855440] xe 0000:03:00.0: pixel repeat: 0
<7> [110.855442] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [110.855443] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [110.855538] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.855633] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.855728] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [110.855729] xe 0000:03:00.0: vendor: Intel
<7> [110.855731] xe 0000:03:00.0: product: Discrete gfx
<7> [110.855732] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [110.855734] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [110.855828] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [110.855923] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [110.856018] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [110.856020] xe 0000:03:00.0: empty frame
<7> [110.856021] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [110.856135] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [110.856220] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [110.856323] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [110.856431] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [110.856516] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [110.856599] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [110.856680] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [110.856757] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [110.856845] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [110.856948] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [110.857049] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [110.857149] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [110.857261] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [110.857381] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [110.857494] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:505:pipe D] allocated TC PLL 3
<7> [110.857597] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:505:pipe D] reserving TC PLL 3
<7> [110.857700] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [110.857806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [110.857908] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [110.858012] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [110.858110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [110.858208] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [110.858306] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [110.858410] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [110.858506] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [110.858603] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [110.858604] xe 0000:03:00.0: colorspace: RGB
<7> [110.858606] xe 0000:03:00.0: scan mode: Underscan
<7> [110.858607] xe 0000:03:00.0: colorimetry: No Data
<7> [110.858608] xe 0000:03:00.0: picture aspect: No Data
<7> [110.858609] xe 0000:03:00.0: active aspect: Same as Picture
<7> [110.858610] xe 0000:03:00.0: itc: No Data
<7> [110.858612] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [110.858613] xe 0000:03:00.0: quantization range: Full
<7> [110.858614] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [110.858615] xe 0000:03:00.0: video code: 0
<7> [110.858616] xe 0000:03:00.0: ycc quantization range: Full
<7> [110.858618] xe 0000:03:00.0: hdmi content type: Graphics
<7> [110.858619] xe 0000:03:00.0: pixel repeat: 0
<7> [110.858620] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [110.858621] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [110.858623] xe 0000:03:00.0: vendor: Intel
<7> [110.858624] xe 0000:03:00.0: product: Discrete gfx
<7> [110.858625] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [110.858627] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [110.858628] xe 0000:03:00.0: empty frame
<7> [110.858629] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [110.858725] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [110.858819] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [110.858914] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [110.859010] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [110.859106] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [110.859202] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [110.859296] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [110.859396] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [110.859491] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [110.859587] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [110.859682] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [110.859777] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [110.859872] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [110.859965] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [110.860059] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [110.860154] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [110.860248] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [110.860341] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [110.860445] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [110.860539] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [110.860634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [110.860727] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [110.860822] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [110.860916] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [110.861009] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [110.861102] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [110.861199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [110.861297] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [110.861400] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [110.861496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [110.861592] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [110.861686] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [110.861781] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [110.861874] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [110.861968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [110.862062] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [110.862156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [110.862250] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.862343] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.862446] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.862538] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [110.862631] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [110.862725] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.862817] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.862910] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.863002] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [110.863095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [110.863190] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [110.863283] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [110.863380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [110.863471] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [110.863565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [110.863657] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [110.863748] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [110.864362] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [110.864475] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [110.864806] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [110.864917] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [110.865013] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [110.865104] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [110.865192] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [110.865280] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [110.865374] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [110.865463] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [110.865554] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [110.865649] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [110.865740] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [110.865827] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [110.865913] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [110.865999] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [110.866085] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [110.866169] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [110.866254] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [110.866555] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [110.866722] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [110.867032] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [110.867131] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [110.869499] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [110.888106] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:505:pipe D], 32 bytes ELD
<7> [110.904773] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [110.905042] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [110.905691] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [110.906162] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [110.910442] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [110.910491] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [110.910650] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:151:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 min link_bpp 18.0000 max link_bpp 36.0000
<7> [110.910927] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [110.911192] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [110.911497] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:151:pipe A]
<7> [110.911753] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [110.911997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [110.912230] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [110.912472] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [110.912691] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [110.912914] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [110.913121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [110.913330] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [110.913554] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [110.913752] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [110.913943] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [110.914128] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [110.914311] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [110.914506] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [110.914683] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [110.914856] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [110.915029] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [110.915197] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [110.915367] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [110.915533] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [110.915691] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [110.915684] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [110.915847] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [110.915998] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [110.916143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [110.916287] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [110.916471] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [110.916612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [110.916750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [110.916884] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [110.917015] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [110.917145] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [110.917281] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [110.917424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [110.917552] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [110.917680] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [110.917800] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [110.917919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [110.918033] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [110.918148] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [110.918265] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [110.918403] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 1024), active pipes 0x8 -> 0x9
<7> [110.918507] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x3, ddb (0 - 2048) -> (1024 - 2048), active pipes 0x8 -> 0x9
<7> [110.918631] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 965), size 0 -> 965
<7> [110.918719] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> ( 965 - 1024), size 0 -> 59
<7> [110.918806] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [110.918892] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [110.918975] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [110.919057] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [110.919139] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:389:plane 1D] ddb ( 0 - 1989) -> (1024 - 1989), size 1989 -> 965
<7> [110.919230] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [110.919340] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [110.919459] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [110.919566] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [110.919679] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [110.919805] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 2
<7> [110.919911] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 2
<7> [110.920017] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [110.920128] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [110.920236] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [110.920340] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [110.920460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [110.920565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [110.920668] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [110.920769] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [110.920872] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [110.920973] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [110.921074] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [110.921175] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [110.921275] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [110.921379] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [110.921481] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [110.921582] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [110.921682] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [110.921782] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [110.921882] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [110.921984] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 42 vsync start: 41, vsync end: 36
<7> [110.922085] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1083, vmax vblank: 1083, vmin vtotal: 1125, vmax vtotal: 1125
<7> [110.922185] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [110.922287] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [110.922409] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [110.922511] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [110.922620] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [110.922724] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [110.922827] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [110.922929] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [110.923030] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [110.923131] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [110.923232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [110.923332] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [110.923441] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [110.923541] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [110.923641] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [110.923740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [110.923841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [110.923940] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [110.924039] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [110.924138] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [110.924237] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [110.924336] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [110.924443] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [110.924543] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [110.924646] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [110.924745] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [110.924849] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [110.924950] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [110.925054] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [110.925158] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [110.925260] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [110.925364] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [110.925466] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.925566] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.925666] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.925767] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [110.925867] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [110.925967] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.926066] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.926165] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [110.926264] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [110.926367] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [110.926469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [110.926571] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [110.926675] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [110.926776] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [110.926875] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [110.926975] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [110.927075] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [110.927948] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [110.928081] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x28000000)
<7> [110.928415] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [110.928516] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [110.928614] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [110.928711] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [110.928810] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [110.928904] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [110.928996] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [110.929087] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [110.929179] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [110.929270] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [110.929363] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [110.929455] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [110.929547] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [110.929639] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [110.929729] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [110.929824] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [110.937766] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [110.938100] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [110.938210] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [110.940496] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [110.943902] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [110.948648] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [110.949664] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [110.950104] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [110.951618] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [110.952179] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [110.958260] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [110.958412] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [110.965188] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [110.965560] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [110.966862] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [110.984684] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:151:pipe A], 40 bytes ELD
<7> [110.999169] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [111.001506] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [111.002338] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [111.069484] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [112.929995] [IGT] kms_flip: finished subtest AD-DP2-HDMI-A3, FAIL
Created at 2026-04-24 11:40:00