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

Result: Fail

i915_display_info15 igt_runner15 results15.json results15-xe-load.json guc_logs15.tar i915_display_info_post_exec15 boot15 dmesg15

DetailValue
Duration 3.45 seconds
Hostname 
shard-bmg-1
Igt-Version 
IGT-Version: 2.3-g0918a5c2c (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xe-4622-50b03c02e68b265fb-debug+ x86_64)
Out 
Starting dynamic subtest: AC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1972 run_pair()
  #3 ../tests/kms_flip.c:2163 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-DP2-HDMI-A3: FAIL (3.447s)
Err 
Starting dynamic subtest: AC-DP2-HDMI-A3
(kms_flip:11411) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:11411) CRITICAL: Failed assertion: end - start < 500
(kms_flip:11411) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest AC-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.321106
last_received_ts = 499.320740
last_seq = 426
current_ts = 499.337769
current_received_ts = 499.337402
current_seq = 427
count = 62
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.337769
last_received_ts = 499.337402
last_seq = 427
current_ts = 499.354431
current_received_ts = 499.354065
current_seq = 428
count = 63
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.354431
last_received_ts = 499.354065
last_seq = 428
current_ts = 499.371094
current_received_ts = 499.370728
current_seq = 429
count = 64
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.371094
last_received_ts = 499.370728
last_seq = 429
current_ts = 499.387756
current_received_ts = 499.387390
current_seq = 430
count = 65
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.387756
last_received_ts = 499.387390
last_seq = 430
current_ts = 499.404449
current_received_ts = 499.404083
current_seq = 431
count = 66
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.404449
last_received_ts = 499.404083
last_seq = 431
current_ts = 499.421112
current_received_ts = 499.420715
current_seq = 432
count = 67
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.421112
last_received_ts = 499.420715
last_seq = 432
current_ts = 499.437775
current_received_ts = 499.437408
current_seq = 433
count = 68
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.437775
last_received_ts = 499.437408
last_seq = 433
current_ts = 499.454437
current_received_ts = 499.454071
current_seq = 434
count = 69
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.454437
last_received_ts = 499.454071
last_seq = 434
current_ts = 499.471100
current_received_ts = 499.470734
current_seq = 435
count = 70
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.471100
last_received_ts = 499.470734
last_seq = 435
current_ts = 499.487762
current_received_ts = 499.487396
current_seq = 436
count = 71
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.487762
last_received_ts = 499.487396
last_seq = 436
current_ts = 499.504425
current_received_ts = 499.504059
current_seq = 437
count = 72
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.504425
last_received_ts = 499.504059
last_seq = 437
current_ts = 499.521088
current_received_ts = 499.520721
current_seq = 438
count = 73
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.521088
last_received_ts = 499.520721
last_seq = 438
current_ts = 499.537750
current_received_ts = 499.537384
current_seq = 439
count = 74
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.537750
last_received_ts = 499.537384
last_seq = 439
current_ts = 499.554413
current_received_ts = 499.553833
current_seq = 440
count = 75
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.554413
last_received_ts = 499.553833
last_seq = 440
current_ts = 499.571106
current_received_ts = 499.570709
current_seq = 441
count = 76
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.571106
last_received_ts = 499.570709
last_seq = 441
current_ts = 499.587738
current_received_ts = 499.587372
current_seq = 442
count = 77
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.587738
last_received_ts = 499.587372
last_seq = 442
current_ts = 499.604431
current_received_ts = 499.604065
current_seq = 443
count = 78
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.604431
last_received_ts = 499.604065
last_seq = 443
current_ts = 499.621094
current_received_ts = 499.620697
current_seq = 444
count = 79
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.621094
last_received_ts = 499.620697
last_seq = 444
current_ts = 499.637756
current_received_ts = 499.637390
current_seq = 445
count = 80
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.637756
last_received_ts = 499.637390
last_seq = 445
current_ts = 499.654419
current_received_ts = 499.654053
current_seq = 446
count = 81
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.654419
last_received_ts = 499.654053
last_seq = 446
current_ts = 499.671082
current_received_ts = 499.670715
current_seq = 447
count = 82
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.671082
last_received_ts = 499.670715
last_seq = 447
current_ts = 499.687744
current_received_ts = 499.687378
current_seq = 448
count = 83
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.687744
last_received_ts = 499.687378
last_seq = 448
current_ts = 499.704407
current_received_ts = 499.704041
current_seq = 449
count = 84
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.704407
last_received_ts = 499.704041
last_seq = 449
current_ts = 499.721069
current_received_ts = 499.720703
current_seq = 450
count = 85
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.721069
last_received_ts = 499.720703
last_seq = 450
current_ts = 499.737732
current_received_ts = 499.737366
current_seq = 451
count = 86
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.737732
last_received_ts = 499.737366
last_seq = 451
current_ts = 499.754395
current_received_ts = 499.754059
current_seq = 452
count = 87
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.754395
last_received_ts = 499.754059
last_seq = 452
current_ts = 499.771057
current_received_ts = 499.770691
current_seq = 453
count = 88
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.771057
last_received_ts = 499.770691
last_seq = 453
current_ts = 499.787750
current_received_ts = 499.787354
current_seq = 454
count = 89
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.787750
last_received_ts = 499.787354
last_seq = 454
current_ts = 499.804413
current_received_ts = 499.804047
current_seq = 455
count = 90
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.804413
last_received_ts = 499.804047
last_seq = 455
current_ts = 499.821075
current_received_ts = 499.820679
current_seq = 456
count = 91
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.821075
last_received_ts = 499.820679
last_seq = 456
current_ts = 499.837738
current_received_ts = 499.837372
current_seq = 457
count = 92
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.837738
last_received_ts = 499.837372
last_seq = 457
current_ts = 499.854401
current_received_ts = 499.853790
current_seq = 458
count = 93
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.854401
last_received_ts = 499.853790
last_seq = 458
current_ts = 499.871063
current_received_ts = 499.870697
current_seq = 459
count = 94
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.871063
last_received_ts = 499.870697
last_seq = 459
current_ts = 499.887726
current_received_ts = 499.887360
current_seq = 460
count = 95
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 31us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.887726
last_received_ts = 499.887360
last_seq = 460
current_ts = 499.904388
current_received_ts = 499.904053
current_seq = 461
count = 96
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.904388
last_received_ts = 499.904053
last_seq = 461
current_ts = 499.921051
current_received_ts = 499.920685
current_seq = 462
count = 97
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.921051
last_received_ts = 499.920685
last_seq = 462
current_ts = 499.937714
current_received_ts = 499.937347
current_seq = 463
count = 98
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 30us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.937714
last_received_ts = 499.937347
last_seq = 463
current_ts = 499.954376
current_received_ts = 499.954041
current_seq = 464
count = 99
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.954376
last_received_ts = 499.954041
last_seq = 464
current_ts = 499.971069
current_received_ts = 499.970673
current_seq = 465
count = 100
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.971069
last_received_ts = 499.970673
last_seq = 465
current_ts = 499.987732
current_received_ts = 499.987335
current_seq = 466
count = 101
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 31us
(kms_flip:11411) DEBUG: name = flip
last_ts = 499.987732
last_received_ts = 499.987335
last_seq = 466
current_ts = 500.004395
current_received_ts = 500.004028
current_seq = 467
count = 102
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.004395
last_received_ts = 500.004028
last_seq = 467
current_ts = 500.021057
current_received_ts = 500.020691
current_seq = 468
count = 103
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.021057
last_received_ts = 500.020691
last_seq = 468
current_ts = 500.037720
current_received_ts = 500.037354
current_seq = 469
count = 104
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 31us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.037720
last_received_ts = 500.037354
last_seq = 469
current_ts = 500.054382
current_received_ts = 500.054016
current_seq = 470
count = 105
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.054382
last_received_ts = 500.054016
last_seq = 470
current_ts = 500.071045
current_received_ts = 500.070679
current_seq = 471
count = 106
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.071045
last_received_ts = 500.070679
last_seq = 471
current_ts = 500.087708
current_received_ts = 500.087341
current_seq = 472
count = 107
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.087708
last_received_ts = 500.087341
last_seq = 472
current_ts = 500.104370
current_received_ts = 500.104034
current_seq = 473
count = 108
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.104370
last_received_ts = 500.104034
last_seq = 473
current_ts = 500.121033
current_received_ts = 500.120667
current_seq = 474
count = 109
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.121033
last_received_ts = 500.120667
last_seq = 474
current_ts = 500.137695
current_received_ts = 500.137329
current_seq = 475
count = 110
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.137695
last_received_ts = 500.137329
last_seq = 475
current_ts = 500.154358
current_received_ts = 500.153778
current_seq = 476
count = 111
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.154358
last_received_ts = 500.153778
last_seq = 476
current_ts = 500.171051
current_received_ts = 500.170654
current_seq = 477
count = 112
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.171051
last_received_ts = 500.170654
last_seq = 477
current_ts = 500.187714
current_received_ts = 500.187317
current_seq = 478
count = 113
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 31us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.187714
last_received_ts = 500.187317
last_seq = 478
current_ts = 500.204376
current_received_ts = 500.204010
current_seq = 479
count = 114
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.204376
last_received_ts = 500.204010
last_seq = 479
current_ts = 500.221039
current_received_ts = 500.220673
current_seq = 480
count = 115
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.221039
last_received_ts = 500.220673
last_seq = 480
current_ts = 500.237701
current_received_ts = 500.237335
current_seq = 481
count = 116
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.237701
last_received_ts = 500.237335
last_seq = 481
current_ts = 500.254364
current_received_ts = 500.254089
current_seq = 482
count = 117
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 239us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.254364
last_received_ts = 500.254089
last_seq = 482
current_ts = 500.271027
current_received_ts = 500.270660
current_seq = 483
count = 118
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.271027
last_received_ts = 500.270660
last_seq = 483
current_ts = 500.287689
current_received_ts = 500.287323
current_seq = 484
count = 119
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 55us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.287689
last_received_ts = 500.287323
last_seq = 484
current_ts = 500.304352
current_received_ts = 500.303986
current_seq = 485
count = 120
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.304352
last_received_ts = 500.303986
last_seq = 485
current_ts = 500.321014
current_received_ts = 500.320648
current_seq = 486
count = 121
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.321014
last_received_ts = 500.320648
last_seq = 486
current_ts = 500.337677
current_received_ts = 500.337311
current_seq = 487
count = 122
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 41us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.337677
last_received_ts = 500.337311
last_seq = 487
current_ts = 500.354340
current_received_ts = 500.353973
current_seq = 488
count = 123
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.354340
last_received_ts = 500.353973
last_seq = 488
current_ts = 500.371033
current_received_ts = 500.370636
current_seq = 489
count = 124
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.371033
last_received_ts = 500.370636
last_seq = 489
current_ts = 500.387695
current_received_ts = 500.387299
current_seq = 490
count = 125
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.387695
last_received_ts = 500.387299
last_seq = 490
current_ts = 500.404358
current_received_ts = 500.403961
current_seq = 491
count = 126
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.404358
last_received_ts = 500.403961
last_seq = 491
current_ts = 500.421021
current_received_ts = 500.420624
current_seq = 492
count = 127
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.421021
last_received_ts = 500.420624
last_seq = 492
current_ts = 500.437683
current_received_ts = 500.437317
current_seq = 493
count = 128
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.437683
last_received_ts = 500.437317
last_seq = 493
current_ts = 500.454346
current_received_ts = 500.453979
current_seq = 494
count = 129
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.454346
last_received_ts = 500.453979
last_seq = 494
current_ts = 500.471008
current_received_ts = 500.470642
current_seq = 495
count = 130
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.471008
last_received_ts = 500.470642
last_seq = 495
current_ts = 500.487671
current_received_ts = 500.487305
current_seq = 496
count = 131
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.487671
last_received_ts = 500.487305
last_seq = 496
current_ts = 500.504333
current_received_ts = 500.503967
current_seq = 497
count = 132
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.504333
last_received_ts = 500.503967
last_seq = 497
current_ts = 500.520996
current_received_ts = 500.520630
current_seq = 498
count = 133
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.520996
last_received_ts = 500.520630
last_seq = 498
current_ts = 500.537659
current_received_ts = 500.537292
current_seq = 499
count = 134
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.537659
last_received_ts = 500.537292
last_seq = 499
current_ts = 500.554321
current_received_ts = 500.553955
current_seq = 500
count = 135
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.554321
last_received_ts = 500.553955
last_seq = 500
current_ts = 500.570984
current_received_ts = 500.570618
current_seq = 501
count = 136
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.570984
last_received_ts = 500.570618
last_seq = 501
current_ts = 500.587677
current_received_ts = 500.587280
current_seq = 502
count = 137
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.587677
last_received_ts = 500.587280
last_seq = 502
current_ts = 500.604340
current_received_ts = 500.603943
current_seq = 503
count = 138
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.604340
last_received_ts = 500.603943
last_seq = 503
current_ts = 500.621002
current_received_ts = 500.620605
current_seq = 504
count = 139
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.621002
last_received_ts = 500.620605
last_seq = 504
current_ts = 500.637665
current_received_ts = 500.637299
current_seq = 505
count = 140
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 79us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.637665
last_received_ts = 500.637299
last_seq = 505
current_ts = 500.654327
current_received_ts = 500.653870
current_seq = 506
count = 141
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 31us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.654327
last_received_ts = 500.653870
last_seq = 506
current_ts = 500.670990
current_received_ts = 500.670593
current_seq = 507
count = 142
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.670990
last_received_ts = 500.670593
last_seq = 507
current_ts = 500.687653
current_received_ts = 500.687286
current_seq = 508
count = 143
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 19us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.687653
last_received_ts = 500.687286
last_seq = 508
current_ts = 500.704315
current_received_ts = 500.703796
current_seq = 509
count = 144
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 16us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.704315
last_received_ts = 500.703796
last_seq = 509
current_ts = 500.720978
current_received_ts = 500.720490
current_seq = 510
count = 145
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 16us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.720978
last_received_ts = 500.720490
last_seq = 510
current_ts = 500.737640
current_received_ts = 500.737091
current_seq = 511
count = 146
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 20us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.737640
last_received_ts = 500.737091
last_seq = 511
current_ts = 500.754303
current_received_ts = 500.753784
current_seq = 512
count = 147
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 20us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.754303
last_received_ts = 500.753784
last_seq = 512
current_ts = 500.770996
current_received_ts = 500.770508
current_seq = 513
count = 148
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.770996
last_received_ts = 500.770508
last_seq = 513
current_ts = 500.787628
current_received_ts = 500.787262
current_seq = 514
count = 149
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 24us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.787628
last_received_ts = 500.787262
last_seq = 514
current_ts = 500.804321
current_received_ts = 500.803925
current_seq = 515
count = 150
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.804321
last_received_ts = 500.803925
last_seq = 515
current_ts = 500.820984
current_received_ts = 500.820618
current_seq = 516
count = 151
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.820984
last_received_ts = 500.820618
last_seq = 516
current_ts = 500.837646
current_received_ts = 500.837280
current_seq = 517
count = 152
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.837646
last_received_ts = 500.837280
last_seq = 517
current_ts = 500.854309
current_received_ts = 500.853943
current_seq = 518
count = 153
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.854309
last_received_ts = 500.853943
last_seq = 518
current_ts = 500.870972
current_received_ts = 500.870605
current_seq = 519
count = 154
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 27us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.870972
last_received_ts = 500.870605
last_seq = 519
current_ts = 500.887634
current_received_ts = 500.887268
current_seq = 520
count = 155
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.887634
last_received_ts = 500.887268
last_seq = 520
current_ts = 500.904297
current_received_ts = 500.903931
current_seq = 521
count = 156
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.904297
last_received_ts = 500.903931
last_seq = 521
current_ts = 500.920959
current_received_ts = 500.920593
current_seq = 522
count = 157
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.920959
last_received_ts = 500.920593
last_seq = 522
current_ts = 500.937653
current_received_ts = 500.937256
current_seq = 523
count = 158
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.937653
last_received_ts = 500.937256
last_seq = 523
current_ts = 500.954285
current_received_ts = 500.953918
current_seq = 524
count = 159
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.954285
last_received_ts = 500.953918
last_seq = 524
current_ts = 500.970978
current_received_ts = 500.970581
current_seq = 525
count = 160
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.970978
last_received_ts = 500.970581
last_seq = 525
current_ts = 500.987610
current_received_ts = 500.987244
current_seq = 526
count = 161
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 500.987610
last_received_ts = 500.987244
last_seq = 526
current_ts = 501.004303
current_received_ts = 501.003906
current_seq = 527
count = 162
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.004303
last_received_ts = 501.003906
last_seq = 527
current_ts = 501.020966
current_received_ts = 501.020569
current_seq = 528
count = 163
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.020966
last_received_ts = 501.020569
last_seq = 528
current_ts = 501.037628
current_received_ts = 501.037231
current_seq = 529
count = 164
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 36us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.037628
last_received_ts = 501.037231
last_seq = 529
current_ts = 501.054291
current_received_ts = 501.053925
current_seq = 530
count = 165
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.054291
last_received_ts = 501.053925
last_seq = 530
current_ts = 501.070953
current_received_ts = 501.070587
current_seq = 531
count = 166
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.070953
last_received_ts = 501.070587
last_seq = 531
current_ts = 501.087616
current_received_ts = 501.087250
current_seq = 532
count = 167
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 38us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.087616
last_received_ts = 501.087250
last_seq = 532
current_ts = 501.104279
current_received_ts = 501.103912
current_seq = 533
count = 168
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.104279
last_received_ts = 501.103912
last_seq = 533
current_ts = 501.120941
current_received_ts = 501.120575
current_seq = 534
count = 169
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.120941
last_received_ts = 501.120575
last_seq = 534
current_ts = 501.137604
current_received_ts = 501.137238
current_seq = 535
count = 170
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 38us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.137604
last_received_ts = 501.137238
last_seq = 535
current_ts = 501.154266
current_received_ts = 501.153900
current_seq = 536
count = 171
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.154266
last_received_ts = 501.153900
last_seq = 536
current_ts = 501.170929
current_received_ts = 501.170563
current_seq = 537
count = 172
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.170929
last_received_ts = 501.170563
last_seq = 537
current_ts = 501.187622
current_received_ts = 501.187225
current_seq = 538
count = 173
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 37us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.187622
last_received_ts = 501.187225
last_seq = 538
current_ts = 501.204285
current_received_ts = 501.203888
current_seq = 539
count = 174
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.204285
last_received_ts = 501.203888
last_seq = 539
current_ts = 501.220947
current_received_ts = 501.220551
current_seq = 540
count = 175
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.220947
last_received_ts = 501.220551
last_seq = 540
current_ts = 501.237610
current_received_ts = 501.237213
current_seq = 541
count = 176
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 37us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.237610
last_received_ts = 501.237213
last_seq = 541
current_ts = 501.254272
current_received_ts = 501.253876
current_seq = 542
count = 177
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.254272
last_received_ts = 501.253876
last_seq = 542
current_ts = 501.270935
current_received_ts = 501.270569
current_seq = 543
count = 178
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.270935
last_received_ts = 501.270569
last_seq = 543
current_ts = 501.287598
current_received_ts = 501.287231
current_seq = 544
count = 179
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.287598
last_received_ts = 501.287231
last_seq = 544
current_ts = 501.304260
current_received_ts = 501.303894
current_seq = 545
count = 180
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 25us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.304260
last_received_ts = 501.303894
last_seq = 545
current_ts = 501.320923
current_received_ts = 501.320557
current_seq = 546
count = 181
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 26us
(kms_flip:11411) DEBUG: name = flip
last_ts = 501.320923
last_received_ts = 501.320557
last_seq = 546
current_ts = 501.337585
current_received_ts = 501.337250
current_seq = 547
count = 182
seq_step = 1
(kms_flip:11411) DEBUG: Vblank took 734us
(kms_flip:11411) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:11411) CRITICAL: Failed assertion: end - start < 500
(kms_flip:11411) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:11411) igt_core-INFO: Stack trace:
(kms_flip:11411) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:11411) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:11411) igt_core-INFO:   #2 ../tests/kms_flip.c:1972 run_pair()
(kms_flip:11411) igt_core-INFO:   #3 ../tests/kms_flip.c:2163 __igt_unique____real_main2043()
(kms_flip:11411) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:11411) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:11411) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:11411) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-DP2-HDMI-A3: FAIL (3.447s)
Dmesg
<6> [513.414931] [IGT] kms_flip: starting dynamic subtest AC-DP2-HDMI-A3
<7> [513.415773] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:554]
<7> [513.416293] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:559]
<7> [513.451494] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [513.451576] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [513.451720] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [513.451831] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [513.451935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [513.452033] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150: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> [513.452128] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [513.452221] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [513.452310] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [513.452399] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [513.452485] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [513.452570] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [513.452658] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [513.452763] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [513.452852] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [513.452937] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [513.453026] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [513.453115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [513.453202] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [513.453289] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [513.453375] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [513.453463] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [513.453560] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [513.453648] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [513.453756] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [513.453852] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [513.453945] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [513.454035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [513.454123] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [513.454212] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [513.454301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [513.454387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [513.454473] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [513.454561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [513.454648] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [513.454749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [513.454836] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [513.454924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [513.455011] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [513.455095] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [513.455183] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [513.455272] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:150:pipe A] releasing TC PLL 2
<7> [513.455393] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [513.455470] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [513.455551] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [513.455656] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [513.455746] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (1989 - 2048) -> (4037 - 4096), size 59 -> 59
<7> [513.455831] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [513.455951] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: no [modeset]
<7> [513.456050] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [513.456147] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [513.456242] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [513.456333] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [513.456429] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [513.456523] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [513.457008] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Disable audio codec on [CRTC:150:pipe A]
<7> [513.474346] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [513.495767] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [513.498421] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:150:pipe A]
<7> [513.500801] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [513.501626] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [513.502087] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [513.502465] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [513.502857] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [513.503202] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [513.503530] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [513.504095] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [513.504378] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [513.504662] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [513.505000] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [513.505298] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [513.505556] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [513.505850] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [513.506090] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [513.506329] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [513.506564] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [513.506820] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]
<7> [513.508887] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [513.509162] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [513.518164] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [513.518833] 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> [513.519427] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [513.520103] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [513.520372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [513.520786] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [513.521152] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [513.521497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [513.521853] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [513.522210] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [513.522501] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [513.523118] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [513.523382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [513.523634] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [513.523935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [513.524197] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [513.524429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [513.524649] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [513.524897] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [513.525101] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [513.525306] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [513.525496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [513.525697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [513.525879] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [513.526071] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [513.526252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [513.526424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [513.526590] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [513.526782] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [513.526946] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [513.527102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [513.527258] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [513.527412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [513.527555] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [513.527709] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [513.527854] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [513.527993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [513.528139] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [513.528279] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [513.528412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [513.528540] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [513.528665] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [513.528810] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [513.528930] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [513.529051] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [513.529170] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [513.529288] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.529408] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [513.529410] xe 0000:03:00.0: colorspace: RGB
<7> [513.529412] xe 0000:03:00.0: scan mode: Underscan
<7> [513.529414] xe 0000:03:00.0: colorimetry: No Data
<7> [513.529415] xe 0000:03:00.0: picture aspect: No Data
<7> [513.529417] xe 0000:03:00.0: active aspect: Same as Picture
<7> [513.529418] xe 0000:03:00.0: itc: No Data
<7> [513.529419] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [513.529421] xe 0000:03:00.0: quantization range: Full
<7> [513.529422] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [513.529424] xe 0000:03:00.0: video code: 0
<7> [513.529425] xe 0000:03:00.0: ycc quantization range: Full
<7> [513.529427] xe 0000:03:00.0: hdmi content type: Graphics
<7> [513.529428] xe 0000:03:00.0: pixel repeat: 0
<7> [513.529429] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [513.529431] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.529544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [513.529652] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.529776] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [513.529778] xe 0000:03:00.0: vendor: Intel
<7> [513.529779] xe 0000:03:00.0: product: Discrete gfx
<7> [513.529781] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [513.529783] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.529888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [513.529994] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.530099] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [513.530101] xe 0000:03:00.0: empty frame
<7> [513.530103] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.530216] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [513.530315] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing TC PLL 3
<7> [513.530451] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [513.530536] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [513.530638] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [513.530768] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [513.530893] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [513.530999] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [513.531098] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [513.531195] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [513.531291] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [513.531387] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [513.531474] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [513.532135] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Disable audio codec on [CRTC:268:pipe B]
<7> [513.551608] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [513.572803] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [513.575269] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [513.576122] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [513.576571] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [513.577077] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [513.577511] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [513.577998] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [513.578411] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [513.578804] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [513.578887] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [513.578966] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [513.579038] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [513.579105] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [513.579380] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [513.579451] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [513.579521] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [513.579591] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [513.579662] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [513.579753] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [513.580081] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [513.580564] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [513.581070] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [513.581696] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [513.582186] 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> [513.582721] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [513.583208] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [513.583906] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [513.584739] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [513.585284] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [513.585321] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [513.585439] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [513.585654] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [513.585889] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:386:pipe C]
<7> [513.586089] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [513.586277] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [513.586458] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [513.586636] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [513.586821] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [513.586979] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [513.587134] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [513.587284] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [513.587429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [513.587561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [513.587711] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [513.587855] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [513.587997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [513.588135] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [513.588269] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [513.588396] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [513.588519] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [513.588637] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [513.588821] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [513.588937] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [513.589051] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [513.589164] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [513.589278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [513.589386] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [513.589494] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [513.589595] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [513.589710] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [513.589812] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [513.589913] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [513.590014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [513.590115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [513.590215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [513.590308] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [513.590399] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [513.590491] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [513.590582] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in dpll_hw_state
<7> [513.590673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.590773] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [513.590859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [513.590946] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [513.591032] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [513.591119] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [513.591205] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [513.591292] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [513.591378] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [513.591464] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [513.591548] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [513.591633] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [513.591728] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [513.591814] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [513.591897] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [513.591980] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [513.592064] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [513.592149] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.592233] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [513.592318] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [513.592400] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [513.592486] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [513.592569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [513.592654] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [513.592759] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [513.592852] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [513.592941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [513.593030] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [513.593117] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [513.593201] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [513.593305] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [513.593393] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [513.593480] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [513.593566] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [513.593653] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [513.593756] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [513.593844] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [513.593931] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [513.594017] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [513.594102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [513.594188] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in avi infoframe
<7> [513.594273] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.594359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.594445] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [513.594447] xe 0000:03:00.0: colorspace: RGB
<7> [513.594448] xe 0000:03:00.0: scan mode: Underscan
<7> [513.594449] xe 0000:03:00.0: colorimetry: No Data
<7> [513.594450] xe 0000:03:00.0: picture aspect: No Data
<7> [513.594451] xe 0000:03:00.0: active aspect: Same as Picture
<7> [513.594452] xe 0000:03:00.0: itc: No Data
<7> [513.594453] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [513.594455] xe 0000:03:00.0: quantization range: Full
<7> [513.594456] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [513.594457] xe 0000:03:00.0: video code: 0
<7> [513.594458] xe 0000:03:00.0: ycc quantization range: Full
<7> [513.594459] xe 0000:03:00.0: hdmi content type: Graphics
<7> [513.594460] xe 0000:03:00.0: pixel repeat: 0
<7> [513.594462] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [513.594463] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in spd infoframe
<7> [513.594548] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.594635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.594744] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [513.594746] xe 0000:03:00.0: vendor: Intel
<7> [513.594747] xe 0000:03:00.0: product: Discrete gfx
<7> [513.594748] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [513.594750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hdmi infoframe
<7> [513.594837] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [513.594922] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [513.595012] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [513.595013] xe 0000:03:00.0: empty frame
<7> [513.595015] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] fastset requirement not met, forcing full modeset
<7> [513.595127] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [513.595204] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:386:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [513.595300] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:270:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [513.595374] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:380:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [513.595445] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [513.595514] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [513.595584] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [513.595653] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] 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> [513.595752] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:386:pipe C] data rate 594000 num active planes 1
<7> [513.595856] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [513.595957] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [513.596056] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [513.596165] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:386:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [513.596280] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [513.596391] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:386:pipe C] allocated TC PLL 3
<7> [513.596484] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:386:pipe C] reserving TC PLL 3
<7> [513.596574] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:386:pipe C] enable: yes [modeset]
<7> [513.596676] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [513.596799] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [513.596897] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [513.596991] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [513.597085] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [513.597178] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [513.597271] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [513.597362] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [513.597455] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [513.597457] xe 0000:03:00.0: colorspace: RGB
<7> [513.597458] xe 0000:03:00.0: scan mode: Underscan
<7> [513.597460] xe 0000:03:00.0: colorimetry: No Data
<7> [513.597461] xe 0000:03:00.0: picture aspect: No Data
<7> [513.597462] xe 0000:03:00.0: active aspect: Same as Picture
<7> [513.597464] xe 0000:03:00.0: itc: No Data
<7> [513.597465] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [513.597466] xe 0000:03:00.0: quantization range: Full
<7> [513.597467] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [513.597469] xe 0000:03:00.0: video code: 0
<7> [513.597470] xe 0000:03:00.0: ycc quantization range: Full
<7> [513.597471] xe 0000:03:00.0: hdmi content type: Graphics
<7> [513.597473] xe 0000:03:00.0: pixel repeat: 0
<7> [513.597474] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [513.597476] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [513.597477] xe 0000:03:00.0: vendor: Intel
<7> [513.597479] xe 0000:03:00.0: product: Discrete gfx
<7> [513.597480] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [513.597482] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [513.597483] xe 0000:03:00.0: empty frame
<7> [513.597484] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [513.597577] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [513.597667] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [513.597776] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [513.597869] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [513.597961] 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> [513.598052] 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> [513.598142] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [513.598232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [513.598321] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [513.598409] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [513.598498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [513.598590] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [513.598680] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [513.598781] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [513.598871] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [513.598962] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [513.599051] 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> [513.599141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [513.599230] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [513.599318] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [513.599407] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [513.599497] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [513.599587] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [513.599673] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [513.599773] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [513.599862] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [513.599949] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [513.600036] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [513.600126] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [513.600216] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [513.600305] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [513.600394] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [513.600483] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [513.600568] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [513.600656] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [513.600758] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.600862] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.600957] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.601049] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [513.601141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [513.601232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.601323] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.601413] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.601504] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [513.601593] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [513.601688] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:270:plane 1C] fb: [FB:554] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [513.601780] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [513.601870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [513.601960] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:300:plane 2C] fb: [NOFB], visible: no
<7> [513.602048] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:330:plane 3C] fb: [NOFB], visible: no
<7> [513.602137] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:360:plane 4C] fb: [NOFB], visible: no
<7> [513.602223] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:370:plane 5C] fb: [NOFB], visible: no
<7> [513.602309] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:380:cursor C] fb: [NOFB], visible: no
<7> [513.602926] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [513.603064] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [513.603424] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [513.603527] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [513.603614] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [513.603703] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [513.603783] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [513.603862] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [513.603941] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [513.604018] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [513.604095] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [513.604171] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [513.604248] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [513.604326] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [513.604402] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [513.604479] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [513.604555] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [513.604630] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [513.604694] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [513.604719] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [513.605022] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [513.605187] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [513.605482] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x4, on? 0) for [CRTC:386:pipe C]
<7> [513.605572] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [513.608068] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [513.626620] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Enable audio codec on [CRTC:386:pipe C], 32 bytes ELD
<7> [513.643240] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [513.643412] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [513.643980] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [513.644381] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:386:pipe C]
<7> [513.648285] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [513.648326] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:526:DP-2]
<7> [513.648465] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:525:DDI TC2/PHY G][CRTC:150: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> [513.648716] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [513.648975] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [513.649222] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:525:DDI TC2/PHY G] [CRTC:150:pipe A]
<7> [513.649467] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [513.649688] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [513.649937] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [513.650150] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [513.650367] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150: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> [513.650568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [513.650775] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [513.650962] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [513.651147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [513.651329] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [513.651503] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [513.651677] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [513.651880] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [513.652047] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [513.652214] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [513.652375] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [513.652533] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [513.652702] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [513.652863] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [513.653014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [513.653164] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [513.653310] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [513.653454] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [513.653598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [513.653752] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [513.653891] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [513.654024] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [513.654158] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [513.654291] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [513.654423] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [513.654551] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [513.654677] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [513.654826] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [513.654947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [513.655068] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [513.655189] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [513.655311] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [513.655427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [513.655543] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [513.655656] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [513.655809] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [513.655906] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x5
<7> [513.656024] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [513.656115] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [513.656204] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [513.656290] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [513.656374] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [513.656458] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34: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> [513.656553] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 594000 num active planes 1
<7> [513.656672] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [513.656799] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [513.656908] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [513.657027] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [513.657146] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [513.657263] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated TC PLL 2
<7> [513.657365] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving TC PLL 2
<7> [513.657459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [513.657561] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [513.657659] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [513.657767] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [513.657862] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [513.657955] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [513.658048] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [513.658140] 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> [513.658233] 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> [513.658326] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [513.658418] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [513.658509] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [513.658600] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [513.658729] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [513.658822] 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> [513.658913] 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> [513.659004] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [513.659095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [513.659185] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [513.659275] 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> [513.659366] 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> [513.659457] 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> [513.659548] 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> [513.659640] 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> [513.659747] 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> [513.659839] 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> [513.659930] 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> [513.660021] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [513.660111] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [513.660202] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [513.660292] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [513.660382] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [513.660473] 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> [513.660563] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [513.660653] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [513.660759] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [513.660855] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [513.660948] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [513.661040] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [513.661130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [513.661220] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [513.661309] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [513.661398] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [513.661487] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [513.661576] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [513.661665] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [513.661764] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [513.661853] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [513.661942] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x40000000 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [513.662031] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [513.662120] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [513.662208] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0db0 0x0000 0x0000
<7> [513.662296] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0db0 0x0000
<7> [513.662384] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0db0
<7> [513.662473] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0100 0x0100 0x0100
<7> [513.662562] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [513.662650] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.662752] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.662841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [513.662930] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [513.663019] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [513.663107] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:554] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [513.663198] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [513.663286] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [513.663375] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [513.663464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [513.663551] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [513.663639] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [513.663734] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [513.664227] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [513.664378] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [513.664690] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [513.664797] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [513.664882] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [513.664965] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [513.665047] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [513.665129] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [513.665210] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [513.665293] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [513.665374] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [513.665457] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [513.665536] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [513.665616] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [513.665703] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [513.665784] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [513.665865] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [513.665943] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [513.667742] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [513.668109] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [513.668213] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [513.668306] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [513.670927] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [513.675123] 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> [513.679870] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [513.680877] 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> [513.681313] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [513.682821] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [513.683375] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [513.689619] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [513.689837] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [513.697634] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [513.698050] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [513.699346] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [513.717199] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Enable audio codec on [CRTC:150:pipe A], 40 bytes ELD
<7> [513.734117] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]
<7> [513.734968] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [513.804755] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [515.709251] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [515.729061] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [516.205281] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x29292828
<7> [516.205441] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x2a292928
<6> [516.865153] [IGT] kms_flip: finished subtest AC-DP2-HDMI-A3, FAIL
Created at 2026-02-26 16:56:14