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

Result: Fail

i915_display_info3 igt_runner3 results3.json results3-xe-load.json guc_logs3.tar i915_display_info_post_exec3 boot3 dmesg3

DetailValue
Duration 3.67 seconds
Hostname 
shard-bmg-6
Igt-Version 
IGT-Version: 2.4-g02b0e01dd (x86_64) (Linux: 7.1.0-rc1-lgci-xe-xe-4948-a53aafc879e9c52b2-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 1084 1089 1125 0x48 0x5 
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 (3.670s)
Err 
Starting dynamic subtest: AD-DP2-HDMI-A3
(kms_flip:3940) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:3940) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3940) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest AD-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 228.936066
last_received_ts = 228.935623
last_seq = 7468
current_ts = 228.952728
current_received_ts = 228.952286
current_seq = 7469
count = 48
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 228.952728
last_received_ts = 228.952286
last_seq = 7469
current_ts = 228.969406
current_received_ts = 228.968918
current_seq = 7470
count = 49
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 228.969406
last_received_ts = 228.968918
last_seq = 7470
current_ts = 228.986069
current_received_ts = 228.985626
current_seq = 7471
count = 50
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 228.986069
last_received_ts = 228.985626
last_seq = 7471
current_ts = 229.002731
current_received_ts = 229.002289
current_seq = 7472
count = 51
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.002731
last_received_ts = 229.002289
last_seq = 7472
current_ts = 229.019409
current_received_ts = 229.018951
current_seq = 7473
count = 52
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.019409
last_received_ts = 229.018951
last_seq = 7473
current_ts = 229.036072
current_received_ts = 229.035583
current_seq = 7474
count = 53
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 27us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.036072
last_received_ts = 229.035583
last_seq = 7474
current_ts = 229.052734
current_received_ts = 229.052292
current_seq = 7475
count = 54
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.052734
last_received_ts = 229.052292
last_seq = 7475
current_ts = 229.069412
current_received_ts = 229.068924
current_seq = 7476
count = 55
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.069412
last_received_ts = 229.068924
last_seq = 7476
current_ts = 229.086075
current_received_ts = 229.085632
current_seq = 7477
count = 56
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.086075
last_received_ts = 229.085632
last_seq = 7477
current_ts = 229.102753
current_received_ts = 229.102264
current_seq = 7478
count = 57
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.102753
last_received_ts = 229.102264
last_seq = 7478
current_ts = 229.119415
current_received_ts = 229.119049
current_seq = 7479
count = 58
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.119415
last_received_ts = 229.119049
last_seq = 7479
current_ts = 229.136078
current_received_ts = 229.135635
current_seq = 7480
count = 59
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.136078
last_received_ts = 229.135635
last_seq = 7480
current_ts = 229.152740
current_received_ts = 229.152374
current_seq = 7481
count = 60
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.152740
last_received_ts = 229.152374
last_seq = 7481
current_ts = 229.169418
current_received_ts = 229.168915
current_seq = 7482
count = 61
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.169418
last_received_ts = 229.168915
last_seq = 7482
current_ts = 229.186081
current_received_ts = 229.185715
current_seq = 7483
count = 62
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.186081
last_received_ts = 229.185715
last_seq = 7483
current_ts = 229.202759
current_received_ts = 229.202271
current_seq = 7484
count = 63
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.202759
last_received_ts = 229.202271
last_seq = 7484
current_ts = 229.219421
current_received_ts = 229.218979
current_seq = 7485
count = 64
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.219421
last_received_ts = 229.218979
last_seq = 7485
current_ts = 229.236084
current_received_ts = 229.235641
current_seq = 7486
count = 65
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.236084
last_received_ts = 229.235641
last_seq = 7486
current_ts = 229.252762
current_received_ts = 229.252319
current_seq = 7487
count = 66
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 24us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.252762
last_received_ts = 229.252319
last_seq = 7487
current_ts = 229.269424
current_received_ts = 229.268982
current_seq = 7488
count = 67
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.269424
last_received_ts = 229.268982
last_seq = 7488
current_ts = 229.286102
current_received_ts = 229.285614
current_seq = 7489
count = 68
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.286102
last_received_ts = 229.285614
last_seq = 7489
current_ts = 229.302765
current_received_ts = 229.302322
current_seq = 7490
count = 69
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.302765
last_received_ts = 229.302322
last_seq = 7490
current_ts = 229.319427
current_received_ts = 229.318939
current_seq = 7491
count = 70
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.319427
last_received_ts = 229.318939
last_seq = 7491
current_ts = 229.336105
current_received_ts = 229.335648
current_seq = 7492
count = 71
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.336105
last_received_ts = 229.335648
last_seq = 7492
current_ts = 229.352768
current_received_ts = 229.352280
current_seq = 7493
count = 72
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.352768
last_received_ts = 229.352280
last_seq = 7493
current_ts = 229.369446
current_received_ts = 229.368942
current_seq = 7494
count = 73
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.369446
last_received_ts = 229.368942
last_seq = 7494
current_ts = 229.386108
current_received_ts = 229.385651
current_seq = 7495
count = 74
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.386108
last_received_ts = 229.385651
last_seq = 7495
current_ts = 229.402771
current_received_ts = 229.402328
current_seq = 7496
count = 75
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.402771
last_received_ts = 229.402328
last_seq = 7496
current_ts = 229.419449
current_received_ts = 229.419067
current_seq = 7497
count = 76
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.419449
last_received_ts = 229.419067
last_seq = 7497
current_ts = 229.436111
current_received_ts = 229.435669
current_seq = 7498
count = 77
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.436111
last_received_ts = 229.435669
last_seq = 7498
current_ts = 229.452789
current_received_ts = 229.452271
current_seq = 7499
count = 78
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.452789
last_received_ts = 229.452271
last_seq = 7499
current_ts = 229.469437
current_received_ts = 229.469070
current_seq = 7500
count = 79
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.469437
last_received_ts = 229.469070
last_seq = 7500
current_ts = 229.486115
current_received_ts = 229.485672
current_seq = 7501
count = 80
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.486115
last_received_ts = 229.485672
last_seq = 7501
current_ts = 229.502792
current_received_ts = 229.502289
current_seq = 7502
count = 81
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.502792
last_received_ts = 229.502289
last_seq = 7502
current_ts = 229.519455
current_received_ts = 229.519028
current_seq = 7503
count = 82
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.519455
last_received_ts = 229.519028
last_seq = 7503
current_ts = 229.536118
current_received_ts = 229.535629
current_seq = 7504
count = 83
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.536118
last_received_ts = 229.535629
last_seq = 7504
current_ts = 229.552780
current_received_ts = 229.552338
current_seq = 7505
count = 84
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.552780
last_received_ts = 229.552338
last_seq = 7505
current_ts = 229.569458
current_received_ts = 229.569031
current_seq = 7506
count = 85
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.569458
last_received_ts = 229.569031
last_seq = 7506
current_ts = 229.586121
current_received_ts = 229.585678
current_seq = 7507
count = 86
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.586121
last_received_ts = 229.585678
last_seq = 7507
current_ts = 229.602798
current_received_ts = 229.602341
current_seq = 7508
count = 87
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.602798
last_received_ts = 229.602341
last_seq = 7508
current_ts = 229.619461
current_received_ts = 229.618973
current_seq = 7509
count = 88
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.619461
last_received_ts = 229.618973
last_seq = 7509
current_ts = 229.636124
current_received_ts = 229.635681
current_seq = 7510
count = 89
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.636124
last_received_ts = 229.635681
last_seq = 7510
current_ts = 229.652802
current_received_ts = 229.652344
current_seq = 7511
count = 90
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.652802
last_received_ts = 229.652344
last_seq = 7511
current_ts = 229.669464
current_received_ts = 229.669037
current_seq = 7512
count = 91
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.669464
last_received_ts = 229.669037
last_seq = 7512
current_ts = 229.686142
current_received_ts = 229.685623
current_seq = 7513
count = 92
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.686142
last_received_ts = 229.685623
last_seq = 7513
current_ts = 229.702805
current_received_ts = 229.702362
current_seq = 7514
count = 93
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.702805
last_received_ts = 229.702362
last_seq = 7514
current_ts = 229.719467
current_received_ts = 229.719040
current_seq = 7515
count = 94
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.719467
last_received_ts = 229.719040
last_seq = 7515
current_ts = 229.736130
current_received_ts = 229.735764
current_seq = 7516
count = 95
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.736130
last_received_ts = 229.735764
last_seq = 7516
current_ts = 229.752808
current_received_ts = 229.752365
current_seq = 7517
count = 96
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.752808
last_received_ts = 229.752365
last_seq = 7517
current_ts = 229.769470
current_received_ts = 229.769104
current_seq = 7518
count = 97
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.769470
last_received_ts = 229.769104
last_seq = 7518
current_ts = 229.786133
current_received_ts = 229.785767
current_seq = 7519
count = 98
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.786133
last_received_ts = 229.785767
last_seq = 7519
current_ts = 229.802811
current_received_ts = 229.802444
current_seq = 7520
count = 99
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.802811
last_received_ts = 229.802444
last_seq = 7520
current_ts = 229.819489
current_received_ts = 229.818985
current_seq = 7521
count = 100
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.819489
last_received_ts = 229.818985
last_seq = 7521
current_ts = 229.836151
current_received_ts = 229.835709
current_seq = 7522
count = 101
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.836151
last_received_ts = 229.835709
last_seq = 7522
current_ts = 229.852814
current_received_ts = 229.852325
current_seq = 7523
count = 102
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.852814
last_received_ts = 229.852325
last_seq = 7523
current_ts = 229.869476
current_received_ts = 229.869064
current_seq = 7524
count = 103
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 31us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.869476
last_received_ts = 229.869064
last_seq = 7524
current_ts = 229.886154
current_received_ts = 229.885757
current_seq = 7525
count = 104
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 27us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.886154
last_received_ts = 229.885757
last_seq = 7525
current_ts = 229.902817
current_received_ts = 229.902328
current_seq = 7526
count = 105
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.902817
last_received_ts = 229.902328
last_seq = 7526
current_ts = 229.919495
current_received_ts = 229.919113
current_seq = 7527
count = 106
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 45us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.919495
last_received_ts = 229.919113
last_seq = 7527
current_ts = 229.936157
current_received_ts = 229.935669
current_seq = 7528
count = 107
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 29us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.936157
last_received_ts = 229.935669
last_seq = 7528
current_ts = 229.952820
current_received_ts = 229.952377
current_seq = 7529
count = 108
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.952820
last_received_ts = 229.952377
last_seq = 7529
current_ts = 229.969498
current_received_ts = 229.969040
current_seq = 7530
count = 109
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.969498
last_received_ts = 229.969040
last_seq = 7530
current_ts = 229.986160
current_received_ts = 229.985703
current_seq = 7531
count = 110
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 229.986160
last_received_ts = 229.985703
last_seq = 7531
current_ts = 230.002823
current_received_ts = 230.002335
current_seq = 7532
count = 111
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.002823
last_received_ts = 230.002335
last_seq = 7532
current_ts = 230.019501
current_received_ts = 230.019028
current_seq = 7533
count = 112
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 29us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.019501
last_received_ts = 230.019028
last_seq = 7533
current_ts = 230.036163
current_received_ts = 230.035690
current_seq = 7534
count = 113
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 27us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.036163
last_received_ts = 230.035690
last_seq = 7534
current_ts = 230.052841
current_received_ts = 230.052353
current_seq = 7535
count = 114
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.052841
last_received_ts = 230.052353
last_seq = 7535
current_ts = 230.069504
current_received_ts = 230.069077
current_seq = 7536
count = 115
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.069504
last_received_ts = 230.069077
last_seq = 7536
current_ts = 230.086166
current_received_ts = 230.085724
current_seq = 7537
count = 116
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.086166
last_received_ts = 230.085724
last_seq = 7537
current_ts = 230.102829
current_received_ts = 230.102386
current_seq = 7538
count = 117
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.102829
last_received_ts = 230.102386
last_seq = 7538
current_ts = 230.119507
current_received_ts = 230.119080
current_seq = 7539
count = 118
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.119507
last_received_ts = 230.119080
last_seq = 7539
current_ts = 230.136169
current_received_ts = 230.135696
current_seq = 7540
count = 119
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.136169
last_received_ts = 230.135696
last_seq = 7540
current_ts = 230.152847
current_received_ts = 230.152405
current_seq = 7541
count = 120
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.152847
last_received_ts = 230.152405
last_seq = 7541
current_ts = 230.169510
current_received_ts = 230.169037
current_seq = 7542
count = 121
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.169510
last_received_ts = 230.169037
last_seq = 7542
current_ts = 230.186172
current_received_ts = 230.185730
current_seq = 7543
count = 122
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.186172
last_received_ts = 230.185730
last_seq = 7543
current_ts = 230.202850
current_received_ts = 230.202362
current_seq = 7544
count = 123
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.202850
last_received_ts = 230.202362
last_seq = 7544
current_ts = 230.219513
current_received_ts = 230.219086
current_seq = 7545
count = 124
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.219513
last_received_ts = 230.219086
last_seq = 7545
current_ts = 230.236176
current_received_ts = 230.235733
current_seq = 7546
count = 125
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.236176
last_received_ts = 230.235733
last_seq = 7546
current_ts = 230.252853
current_received_ts = 230.252365
current_seq = 7547
count = 126
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 32us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.252853
last_received_ts = 230.252365
last_seq = 7547
current_ts = 230.269516
current_received_ts = 230.269104
current_seq = 7548
count = 127
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.269516
last_received_ts = 230.269104
last_seq = 7548
current_ts = 230.286194
current_received_ts = 230.285706
current_seq = 7549
count = 128
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.286194
last_received_ts = 230.285706
last_seq = 7549
current_ts = 230.302856
current_received_ts = 230.302414
current_seq = 7550
count = 129
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.302856
last_received_ts = 230.302414
last_seq = 7550
current_ts = 230.319519
current_received_ts = 230.319107
current_seq = 7551
count = 130
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.319519
last_received_ts = 230.319107
last_seq = 7551
current_ts = 230.336197
current_received_ts = 230.335739
current_seq = 7552
count = 131
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.336197
last_received_ts = 230.335739
last_seq = 7552
current_ts = 230.352859
current_received_ts = 230.352356
current_seq = 7553
count = 132
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.352859
last_received_ts = 230.352356
last_seq = 7553
current_ts = 230.369522
current_received_ts = 230.369156
current_seq = 7554
count = 133
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.369522
last_received_ts = 230.369156
last_seq = 7554
current_ts = 230.386200
current_received_ts = 230.385696
current_seq = 7555
count = 134
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.386200
last_received_ts = 230.385696
last_seq = 7555
current_ts = 230.402863
current_received_ts = 230.402420
current_seq = 7556
count = 135
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.402863
last_received_ts = 230.402420
last_seq = 7556
current_ts = 230.419540
current_received_ts = 230.419067
current_seq = 7557
count = 136
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 24us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.419540
last_received_ts = 230.419067
last_seq = 7557
current_ts = 230.436203
current_received_ts = 230.435760
current_seq = 7558
count = 137
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.436203
last_received_ts = 230.435760
last_seq = 7558
current_ts = 230.452866
current_received_ts = 230.452423
current_seq = 7559
count = 138
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.452866
last_received_ts = 230.452423
last_seq = 7559
current_ts = 230.469543
current_received_ts = 230.469070
current_seq = 7560
count = 139
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.469543
last_received_ts = 230.469070
last_seq = 7560
current_ts = 230.486206
current_received_ts = 230.485764
current_seq = 7561
count = 140
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.486206
last_received_ts = 230.485764
last_seq = 7561
current_ts = 230.502869
current_received_ts = 230.502426
current_seq = 7562
count = 141
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.502869
last_received_ts = 230.502426
last_seq = 7562
current_ts = 230.519547
current_received_ts = 230.519073
current_seq = 7563
count = 142
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.519547
last_received_ts = 230.519073
last_seq = 7563
current_ts = 230.536209
current_received_ts = 230.535767
current_seq = 7564
count = 143
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.536209
last_received_ts = 230.535767
last_seq = 7564
current_ts = 230.552872
current_received_ts = 230.552429
current_seq = 7565
count = 144
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.552872
last_received_ts = 230.552429
last_seq = 7565
current_ts = 230.569550
current_received_ts = 230.569077
current_seq = 7566
count = 145
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.569550
last_received_ts = 230.569077
last_seq = 7566
current_ts = 230.586212
current_received_ts = 230.585770
current_seq = 7567
count = 146
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.586212
last_received_ts = 230.585770
last_seq = 7567
current_ts = 230.602890
current_received_ts = 230.602417
current_seq = 7568
count = 147
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.602890
last_received_ts = 230.602417
last_seq = 7568
current_ts = 230.619553
current_received_ts = 230.619141
current_seq = 7569
count = 148
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.619553
last_received_ts = 230.619141
last_seq = 7569
current_ts = 230.636215
current_received_ts = 230.635712
current_seq = 7570
count = 149
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.636215
last_received_ts = 230.635712
last_seq = 7570
current_ts = 230.652878
current_received_ts = 230.652435
current_seq = 7571
count = 150
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.652878
last_received_ts = 230.652435
last_seq = 7571
current_ts = 230.669556
current_received_ts = 230.669113
current_seq = 7572
count = 151
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.669556
last_received_ts = 230.669113
last_seq = 7572
current_ts = 230.686234
current_received_ts = 230.685715
current_seq = 7573
count = 152
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.686234
last_received_ts = 230.685715
last_seq = 7573
current_ts = 230.702896
current_received_ts = 230.702454
current_seq = 7574
count = 153
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 72us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.702896
last_received_ts = 230.702454
last_seq = 7574
current_ts = 230.719559
current_received_ts = 230.719101
current_seq = 7575
count = 154
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.719559
last_received_ts = 230.719101
last_seq = 7575
current_ts = 230.736221
current_received_ts = 230.735779
current_seq = 7576
count = 155
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.736221
last_received_ts = 230.735779
last_seq = 7576
current_ts = 230.752899
current_received_ts = 230.752441
current_seq = 7577
count = 156
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 54us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.752899
last_received_ts = 230.752441
last_seq = 7577
current_ts = 230.769577
current_received_ts = 230.769073
current_seq = 7578
count = 157
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 24us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.769577
last_received_ts = 230.769073
last_seq = 7578
current_ts = 230.786224
current_received_ts = 230.785858
current_seq = 7579
count = 158
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 177us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.786224
last_received_ts = 230.785858
last_seq = 7579
current_ts = 230.802902
current_received_ts = 230.802399
current_seq = 7580
count = 159
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 52us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.802902
last_received_ts = 230.802399
last_seq = 7580
current_ts = 230.819565
current_received_ts = 230.819122
current_seq = 7581
count = 160
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.819565
last_received_ts = 230.819122
last_seq = 7581
current_ts = 230.836243
current_received_ts = 230.835785
current_seq = 7582
count = 161
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.836243
last_received_ts = 230.835785
last_seq = 7582
current_ts = 230.852905
current_received_ts = 230.852524
current_seq = 7583
count = 162
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 61us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.852905
last_received_ts = 230.852524
last_seq = 7583
current_ts = 230.869583
current_received_ts = 230.869156
current_seq = 7584
count = 163
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.869583
last_received_ts = 230.869156
last_seq = 7584
current_ts = 230.886246
current_received_ts = 230.885910
current_seq = 7585
count = 164
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 84us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.886246
last_received_ts = 230.885910
last_seq = 7585
current_ts = 230.902924
current_received_ts = 230.902542
current_seq = 7586
count = 165
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 25us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.902924
last_received_ts = 230.902542
last_seq = 7586
current_ts = 230.919586
current_received_ts = 230.919113
current_seq = 7587
count = 166
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 26us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.919586
last_received_ts = 230.919113
last_seq = 7587
current_ts = 230.936249
current_received_ts = 230.935867
current_seq = 7588
count = 167
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 27us
(kms_flip:3940) DEBUG: name = flip
last_ts = 230.936249
last_received_ts = 230.935867
last_seq = 7588
current_ts = 230.952911
current_received_ts = 230.952423
current_seq = 7589
count = 168
seq_step = 1
(kms_flip:3940) DEBUG: Vblank took 724us
(kms_flip:3940) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:3940) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3940) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3940) igt_core-INFO: Stack trace:
(kms_flip:3940) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3940) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:3940) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:3940) igt_core-INFO:   #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
(kms_flip:3940) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:3940) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3940) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3940) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AD-DP2-HDMI-A3: FAIL (3.670s)
Dmesg
<6> [227.617516] [IGT] kms_flip: starting dynamic subtest AD-DP2-HDMI-A3
<7> [227.620417] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [227.622244] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [227.669928] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [227.670007] 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> [227.670141] 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> [227.670253] 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> [227.670360] 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> [227.670464] 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> [227.670569] 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> [227.670673] 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> [227.670786] 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> [227.670893] 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> [227.670997] 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> [227.671098] 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> [227.671203] 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> [227.671308] 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> [227.671410] 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> [227.671516] 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> [227.671621] 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> [227.671728] 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> [227.671844] 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> [227.671950] 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> [227.672050] 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> [227.672153] 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> [227.672257] 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> [227.672359] 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> [227.672459] 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> [227.672558] 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> [227.672658] 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> [227.672773] 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> [227.672880] 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> [227.672982] 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> [227.673084] 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> [227.673183] 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> [227.673282] 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> [227.673381] 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> [227.673479] 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> [227.673578] 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> [227.673683] 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> [227.673829] 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> [227.673931] 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> [227.674032] 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> [227.674133] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [227.674234] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [227.674365] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [227.674450] 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> [227.674543] 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> [227.674650] 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> [227.674730] 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> [227.674822] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [227.674961] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [227.675075] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [227.675184] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [227.675290] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [227.675393] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [227.675496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [227.675599] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [227.676094] 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> [227.693530] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [227.693989] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [227.711019] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [227.713550] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [227.715948] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [227.716552] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [227.716952] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [227.717291] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [227.717593] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [227.717923] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [227.718217] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [227.718552] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [227.718855] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [227.718720] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [227.719123] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [227.719375] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [227.719630] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [227.719909] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [227.720156] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [227.720393] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [227.720630] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [227.720883] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [227.721106] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [227.723011] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [227.723271] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [227.740105] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [227.740789] 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> [227.741482] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [227.742694] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [227.743230] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [227.743476] 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> [227.744130] 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> [227.744709] 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> [227.745266] 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> [227.745741] 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> [227.746259] 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> [227.746681] 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> [227.747124] 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> [227.747497] 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> [227.747915] 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> [227.748258] 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> [227.748607] 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> [227.748992] 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> [227.749317] 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> [227.749628] 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 1084, found 0)
<7> [227.749954] 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 1089, found 0)
<7> [227.750232] 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> [227.750506] 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> [227.750772] 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> [227.751027] 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> [227.751268] 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> [227.751504] 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> [227.751728] 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> [227.751978] 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> [227.752197] 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> [227.752416] 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> [227.752631] 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 1084, found 0)
<7> [227.752908] 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 1089, found 0)
<7> [227.753120] 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> [227.753313] 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> [227.753501] 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> [227.753682] 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> [227.753886] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [227.754064] 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> [227.754238] 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> [227.754407] 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> [227.754566] 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> [227.754719] 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> [227.754888] 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> [227.755039] 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> [227.755185] 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> [227.755328] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [227.755471] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.755607] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [227.755609] xe 0000:03:00.0: colorspace: RGB
<7> [227.755611] xe 0000:03:00.0: scan mode: Underscan
<7> [227.755612] xe 0000:03:00.0: colorimetry: No Data
<7> [227.755614] xe 0000:03:00.0: picture aspect: 16:9
<7> [227.755616] xe 0000:03:00.0: active aspect: Same as Picture
<7> [227.755617] xe 0000:03:00.0: itc: No Data
<7> [227.755619] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [227.755620] xe 0000:03:00.0: quantization range: Default
<7> [227.755622] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [227.755624] xe 0000:03:00.0: video code: 16
<7> [227.755625] xe 0000:03:00.0: ycc quantization range: Full
<7> [227.755627] xe 0000:03:00.0: hdmi content type: Graphics
<7> [227.755629] xe 0000:03:00.0: pixel repeat: 0
<7> [227.755630] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [227.755632] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.755772] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [227.755906] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.756033] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [227.756034] xe 0000:03:00.0: vendor: Intel
<7> [227.756036] xe 0000:03:00.0: product: Discrete gfx
<7> [227.756038] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [227.756039] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.756170] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [227.756297] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.756420] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [227.756422] xe 0000:03:00.0: empty frame
<7> [227.756424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.756544] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [227.756661] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe B] releasing TC PLL 3
<7> [227.756811] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [227.756912] 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> [227.757024] 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> [227.757110] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [227.757248] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: no [modeset]
<7> [227.757362] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [NOFB], visible: no
<7> [227.757468] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [227.757576] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [227.757683] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [227.757798] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [227.757902] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [227.758567] 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> [227.790279] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [227.791397] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [227.803317] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [227.807030] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:387:pipe B]
<7> [227.809675] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [227.810631] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [227.811219] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [227.811766] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [227.812285] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [227.812792] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [227.813269] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [227.813759] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [227.814244] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [227.814738] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [227.815258] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [227.815683] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [227.816344] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [227.816719] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [227.817121] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [227.817485] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [227.817852] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [227.818184] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [227.818532] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [227.819052] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [227.819518] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [227.820153] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [227.821223] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [227.821702] 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> [227.822193] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [227.822435] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [227.823018] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [227.823755] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [227.823785] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [227.823866] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [227.824028] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [227.824206] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:505:pipe D]
<7> [227.824369] 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> [227.824523] 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> [227.824669] 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> [227.824834] 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> [227.824975] 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> [227.825108] 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> [227.825238] 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> [227.825369] 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> [227.825493] 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> [227.825612] 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> [227.825729] 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> [227.825862] 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> [227.825975] 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> [227.826086] 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> [227.826195] 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 1084)
<7> [227.826299] 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 1089)
<7> [227.826405] 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> [227.826516] 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> [227.826620] 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> [227.826723] 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> [227.826835] 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> [227.826935] 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> [227.827038] 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> [227.827143] 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> [227.827246] 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> [227.827347] 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> [227.827448] 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 1084)
<7> [227.827547] 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 1089)
<7> [227.827650] 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> [227.827762] 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> [227.827865] 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> [227.827967] 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> [227.828068] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [227.828169] 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> [227.828270] 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> [227.828375] 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> [227.828482] 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> [227.828591] 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> [227.828698] 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> [227.828814] 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> [227.828921] 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> [227.829024] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [227.829126] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.829226] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.829332] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [227.829334] xe 0000:03:00.0: colorspace: RGB
<7> [227.829335] xe 0000:03:00.0: scan mode: Underscan
<7> [227.829337] xe 0000:03:00.0: colorimetry: No Data
<7> [227.829338] xe 0000:03:00.0: picture aspect: 16:9
<7> [227.829339] xe 0000:03:00.0: active aspect: Same as Picture
<7> [227.829340] xe 0000:03:00.0: itc: No Data
<7> [227.829341] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [227.829343] xe 0000:03:00.0: quantization range: Default
<7> [227.829344] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [227.829345] xe 0000:03:00.0: video code: 16
<7> [227.829346] xe 0000:03:00.0: ycc quantization range: Full
<7> [227.829348] xe 0000:03:00.0: hdmi content type: Graphics
<7> [227.829349] xe 0000:03:00.0: pixel repeat: 0
<7> [227.829350] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [227.829352] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [227.829456] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.829557] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.829657] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [227.829658] xe 0000:03:00.0: vendor: Intel
<7> [227.829659] xe 0000:03:00.0: product: Discrete gfx
<7> [227.829661] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [227.829662] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [227.829766] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [227.829868] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [227.829967] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [227.829969] xe 0000:03:00.0: empty frame
<7> [227.829971] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [227.830095] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [227.830182] 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> [227.830283] 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> [227.830363] 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> [227.830443] 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> [227.830523] 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> [227.830599] 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> [227.830674] 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> [227.830771] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [227.830885] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [227.830993] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [227.831098] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [227.831213] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [227.831330] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [227.831447] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:505:pipe D] allocated TC PLL 3
<7> [227.831549] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:505:pipe D] reserving TC PLL 3
<7> [227.831650] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [227.831772] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [227.831881] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [227.831986] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [227.832089] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [227.832192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [227.832294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [227.832395] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [227.832496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [227.832597] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [227.832598] xe 0000:03:00.0: colorspace: RGB
<7> [227.832600] xe 0000:03:00.0: scan mode: Underscan
<7> [227.832601] xe 0000:03:00.0: colorimetry: No Data
<7> [227.832602] xe 0000:03:00.0: picture aspect: 16:9
<7> [227.832603] xe 0000:03:00.0: active aspect: Same as Picture
<7> [227.832604] xe 0000:03:00.0: itc: No Data
<7> [227.832606] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [227.832607] xe 0000:03:00.0: quantization range: Default
<7> [227.832608] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [227.832609] xe 0000:03:00.0: video code: 16
<7> [227.832611] xe 0000:03:00.0: ycc quantization range: Full
<7> [227.832612] xe 0000:03:00.0: hdmi content type: Graphics
<7> [227.832613] xe 0000:03:00.0: pixel repeat: 0
<7> [227.832614] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [227.832616] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [227.832617] xe 0000:03:00.0: vendor: Intel
<7> [227.832618] xe 0000:03:00.0: product: Discrete gfx
<7> [227.832620] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [227.832621] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [227.832622] xe 0000:03:00.0: empty frame
<7> [227.832624] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [227.832724] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [227.832836] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [227.832936] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [227.833036] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [227.833136] 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: 41, vsync end: 36
<7> [227.833238] 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> [227.833338] 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> [227.833447] 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> [227.833553] 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> [227.833656] 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> [227.833764] 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> [227.833866] 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> [227.833967] 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> [227.834067] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [227.834167] 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> [227.834267] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [227.834373] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [227.834484] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [227.834590] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [227.834695] 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> [227.834806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [227.834908] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [227.835009] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [227.835110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [227.835213] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [227.835322] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [227.835431] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [227.835543] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [227.835653] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [227.835766] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [227.835874] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [227.835979] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [227.836082] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [227.836186] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [227.836288] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [227.836391] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [227.836492] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [227.836594] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [227.836696] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.836804] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.836905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.837004] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [227.837104] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [227.837204] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.837304] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.837404] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.837506] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [227.837613] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [FB:557] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [227.837719] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [227.837835] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [227.837939] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [227.838040] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [227.838142] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [227.838242] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [227.838341] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [227.838962] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [227.839078] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [227.839202] 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> [227.839317] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [227.839415] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [227.839507] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [227.839598] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [227.839692] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [227.839814] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [227.839902] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [227.839990] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [227.840080] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [227.840168] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [227.840252] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [227.840336] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [227.840426] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [227.840513] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [227.840603] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [227.840696] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [227.840803] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [227.840974] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [227.841081] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [227.841183] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [227.843348] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [227.862263] 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], 36 bytes ELD
<7> [227.878518] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [227.878876] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [227.879556] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [227.880093] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [227.884497] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [227.884547] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [227.884704] 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> [227.885023] 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> [227.885293] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [227.885582] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:151:pipe A]
<7> [227.885863] 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> [227.885993] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [227.886118] 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> [227.886359] 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> [227.886596] 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> [227.886844] 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> [227.887087] 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> [227.887302] 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> [227.887522] 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> [227.887726] 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> [227.887953] 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> [227.888151] 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> [227.888341] 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> [227.888523] 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> [227.888704] 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> [227.888904] 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> [227.889087] 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> [227.889268] 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> [227.889449] 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> [227.889620] 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> [227.889793] 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> [227.889958] 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> [227.890122] 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> [227.890283] 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> [227.890440] 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> [227.890587] 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> [227.890734] 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> [227.890896] 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> [227.891043] 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> [227.891186] 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> [227.891321] 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> [227.891455] 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> [227.891583] 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> [227.891712] 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> [227.891850] 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> [227.891976] 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> [227.892095] 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> [227.892214] 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> [227.892331] 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> [227.892446] 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> [227.892558] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [227.892688] 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> [227.892805] 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> [227.892927] 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> [227.893018] 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> [227.893101] 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> [227.893188] 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> [227.893273] 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> [227.893356] 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> [227.893437] 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> [227.893527] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [227.893646] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [227.893769] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [227.893882] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [227.894005] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [227.894138] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 2
<7> [227.894250] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 2
<7> [227.894362] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [227.894484] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [227.894604] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [227.894723] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [227.894850] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [227.894964] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [227.895077] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [227.895186] 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> [227.895296] 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> [227.895408] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [227.895521] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [227.895632] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [227.895746] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [227.895861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [227.895974] 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> [227.896086] 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> [227.896197] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [227.896308] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [227.896417] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [227.896525] 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> [227.896639] 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> [227.896757] 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> [227.896871] 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> [227.896991] 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> [227.897112] 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> [227.897229] 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> [227.897346] 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> [227.897462] 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> [227.897573] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [227.897687] 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> [227.897839] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [227.897954] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [227.898068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [227.898183] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [227.898296] 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> [227.898407] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [227.898516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [227.898625] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [227.898735] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [227.898856] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [227.898965] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [227.899080] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [227.899192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [227.899302] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [227.899410] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [227.899519] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [227.899626] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [227.899734] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [227.899852] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [227.899961] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [227.900068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [227.900175] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [227.900282] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [227.900389] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.900495] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.900602] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.900709] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [227.900822] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [227.900933] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.901051] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.901166] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [227.901279] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [227.901389] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:557] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [227.901500] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [227.901609] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [227.901718] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [227.901841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [227.901949] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [227.902056] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [227.902171] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [227.903068] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [227.903210] 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> [227.903543] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [227.903651] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [227.903758] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [227.903857] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [227.903956] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [227.904053] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [227.904154] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [227.904253] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [227.904348] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [227.904442] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [227.904536] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [227.904633] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [227.904731] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [227.904836] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [227.904932] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [227.905025] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [227.911764] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [227.911974] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [227.912087] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [227.914583] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [227.918578] 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> [227.923328] 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> [227.924352] 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> [227.924801] 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> [227.926313] 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> [227.926883] 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> [227.934686] 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> [227.934993] 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> [227.941715] 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> [227.942082] 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> [227.943411] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [227.961421] 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> [227.974566] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [227.978120] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [227.978820] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [228.043865] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [229.988940] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [230.092788] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [231.294724] [IGT] kms_flip: finished subtest AD-DP2-HDMI-A3, FAIL
Created at 2026-04-29 15:40:28