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

Result: Fail

i915_display_info14 igt_runner14 results14.json results14-xe-load.json guc_logs14.tar i915_display_info_post_exec14 serial_data14 boot14 dmesg14

DetailValue
Duration 2.94 seconds
Hostname 
shard-bmg-10
Igt-Version 
IGT-Version: 2.4-g22222b7d9 (x86_64) (Linux: 7.1.0-rc3-lgci-xe-xe-5062-dee34cfbffbfe2196-debug+ x86_64)
Out 
Starting dynamic subtest: BC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest BC-DP2-HDMI-A3: FAIL (2.943s)
Err 
Starting dynamic subtest: BC-DP2-HDMI-A3
[166.754674] (kms_flip:4824) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[166.754752] (kms_flip:4824) CRITICAL: Failed assertion: end - start < 500
[166.754831] (kms_flip:4824) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest BC-DP2-HDMI-A3 failed.
**** DEBUG ****
[164.736899] (kms_flip:4824) DEBUG: Vblank took 29us
[164.753527] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.504745
last_received_ts = 166.504150
last_seq = 4436
current_ts = 166.521408
current_received_ts = 166.520813
current_seq = 4437
count = 4
seq_step = 1
[164.753560] (kms_flip:4824) DEBUG: Vblank took 27us
[164.770195] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.521408
last_received_ts = 166.520813
last_seq = 4437
current_ts = 166.538071
current_received_ts = 166.537476
current_seq = 4438
count = 5
seq_step = 1
[164.770226] (kms_flip:4824) DEBUG: Vblank took 26us
[164.786860] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.538071
last_received_ts = 166.537476
last_seq = 4438
current_ts = 166.554733
current_received_ts = 166.554138
current_seq = 4439
count = 6
seq_step = 1
[164.786891] (kms_flip:4824) DEBUG: Vblank took 26us
[164.803527] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.554733
last_received_ts = 166.554138
last_seq = 4439
current_ts = 166.571411
current_received_ts = 166.570816
current_seq = 4440
count = 7
seq_step = 1
[164.803559] (kms_flip:4824) DEBUG: Vblank took 25us
[164.820196] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.571411
last_received_ts = 166.570816
last_seq = 4440
current_ts = 166.588074
current_received_ts = 166.587479
current_seq = 4441
count = 8
seq_step = 1
[164.820226] (kms_flip:4824) DEBUG: Vblank took 26us
[164.836862] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.588074
last_received_ts = 166.587479
last_seq = 4441
current_ts = 166.604736
current_received_ts = 166.604141
current_seq = 4442
count = 9
seq_step = 1
[164.836893] (kms_flip:4824) DEBUG: Vblank took 26us
[164.853531] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.604736
last_received_ts = 166.604141
last_seq = 4442
current_ts = 166.621399
current_received_ts = 166.620819
current_seq = 4443
count = 10
seq_step = 1
[164.853560] (kms_flip:4824) DEBUG: Vblank took 25us
[164.870223] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.621399
last_received_ts = 166.620819
last_seq = 4443
current_ts = 166.638077
current_received_ts = 166.637497
current_seq = 4444
count = 11
seq_step = 1
[164.870272] (kms_flip:4824) DEBUG: Vblank took 36us
[164.886868] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.638077
last_received_ts = 166.637497
last_seq = 4444
current_ts = 166.654739
current_received_ts = 166.654144
current_seq = 4445
count = 12
seq_step = 1
[164.886901] (kms_flip:4824) DEBUG: Vblank took 27us
[164.903534] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.654739
last_received_ts = 166.654144
last_seq = 4445
current_ts = 166.671402
current_received_ts = 166.670807
current_seq = 4446
count = 13
seq_step = 1
[164.903565] (kms_flip:4824) DEBUG: Vblank took 26us
[164.920202] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.671402
last_received_ts = 166.670807
last_seq = 4446
current_ts = 166.688080
current_received_ts = 166.687485
current_seq = 4447
count = 14
seq_step = 1
[164.920232] (kms_flip:4824) DEBUG: Vblank took 26us
[164.936868] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.688080
last_received_ts = 166.687485
last_seq = 4447
current_ts = 166.704742
current_received_ts = 166.704147
current_seq = 4448
count = 15
seq_step = 1
[164.936899] (kms_flip:4824) DEBUG: Vblank took 25us
[164.953684] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.704742
last_received_ts = 166.704147
last_seq = 4448
current_ts = 166.721405
current_received_ts = 166.720963
current_seq = 4449
count = 16
seq_step = 1
[164.953714] (kms_flip:4824) DEBUG: Vblank took 26us
[164.970352] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.721405
last_received_ts = 166.720963
last_seq = 4449
current_ts = 166.738068
current_received_ts = 166.737625
current_seq = 4450
count = 17
seq_step = 1
[164.970382] (kms_flip:4824) DEBUG: Vblank took 26us
[164.987019] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.738068
last_received_ts = 166.737625
last_seq = 4450
current_ts = 166.754745
current_received_ts = 166.754288
current_seq = 4451
count = 18
seq_step = 1
[164.987048] (kms_flip:4824) DEBUG: Vblank took 26us
[165.003645] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.754745
last_received_ts = 166.754288
last_seq = 4451
current_ts = 166.771408
current_received_ts = 166.770920
current_seq = 4452
count = 19
seq_step = 1
[165.003676] (kms_flip:4824) DEBUG: Vblank took 26us
[165.020355] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.771408
last_received_ts = 166.770920
last_seq = 4452
current_ts = 166.788071
current_received_ts = 166.787628
current_seq = 4453
count = 20
seq_step = 1
[165.020386] (kms_flip:4824) DEBUG: Vblank took 26us
[165.036872] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.788071
last_received_ts = 166.787628
last_seq = 4453
current_ts = 166.804749
current_received_ts = 166.804153
current_seq = 4454
count = 21
seq_step = 1
[165.036903] (kms_flip:4824) DEBUG: Vblank took 26us
[165.053540] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.804749
last_received_ts = 166.804153
last_seq = 4454
current_ts = 166.821411
current_received_ts = 166.820816
current_seq = 4455
count = 22
seq_step = 1
[165.053571] (kms_flip:4824) DEBUG: Vblank took 26us
[165.070230] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.821411
last_received_ts = 166.820816
last_seq = 4455
current_ts = 166.838074
current_received_ts = 166.837494
current_seq = 4456
count = 23
seq_step = 1
[165.070277] (kms_flip:4824) DEBUG: Vblank took 35us
[165.086882] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.838074
last_received_ts = 166.837494
last_seq = 4456
current_ts = 166.854736
current_received_ts = 166.854156
current_seq = 4457
count = 24
seq_step = 1
[165.086919] (kms_flip:4824) DEBUG: Vblank took 28us
[165.103543] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.854736
last_received_ts = 166.854156
last_seq = 4457
current_ts = 166.871414
current_received_ts = 166.870819
current_seq = 4458
count = 25
seq_step = 1
[165.103575] (kms_flip:4824) DEBUG: Vblank took 27us
[165.120213] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.871414
last_received_ts = 166.870819
last_seq = 4458
current_ts = 166.888077
current_received_ts = 166.887482
current_seq = 4459
count = 26
seq_step = 1
[165.120244] (kms_flip:4824) DEBUG: Vblank took 25us
[165.136877] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.888077
last_received_ts = 166.887482
last_seq = 4459
current_ts = 166.904739
current_received_ts = 166.904144
current_seq = 4460
count = 27
seq_step = 1
[165.136907] (kms_flip:4824) DEBUG: Vblank took 25us
[165.153545] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.904739
last_received_ts = 166.904144
last_seq = 4460
current_ts = 166.921417
current_received_ts = 166.920822
current_seq = 4461
count = 28
seq_step = 1
[165.153575] (kms_flip:4824) DEBUG: Vblank took 25us
[165.170215] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.921417
last_received_ts = 166.920822
last_seq = 4461
current_ts = 166.938080
current_received_ts = 166.937485
current_seq = 4462
count = 29
seq_step = 1
[165.170245] (kms_flip:4824) DEBUG: Vblank took 25us
[165.186879] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.938080
last_received_ts = 166.937485
last_seq = 4462
current_ts = 166.954742
current_received_ts = 166.954147
current_seq = 4463
count = 30
seq_step = 1
[165.186909] (kms_flip:4824) DEBUG: Vblank took 26us
[165.203548] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.954742
last_received_ts = 166.954147
last_seq = 4463
current_ts = 166.971405
current_received_ts = 166.970810
current_seq = 4464
count = 31
seq_step = 1
[165.203578] (kms_flip:4824) DEBUG: Vblank took 25us
[165.220217] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.971405
last_received_ts = 166.970810
last_seq = 4464
current_ts = 166.988083
current_received_ts = 166.987488
current_seq = 4465
count = 32
seq_step = 1
[165.220247] (kms_flip:4824) DEBUG: Vblank took 25us
[165.236882] (kms_flip:4824) DEBUG: name = flip
last_ts = 166.988083
last_received_ts = 166.987488
last_seq = 4465
current_ts = 167.004745
current_received_ts = 167.004150
current_seq = 4466
count = 33
seq_step = 1
[165.236913] (kms_flip:4824) DEBUG: Vblank took 26us
[165.253698] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.004745
last_received_ts = 167.004150
last_seq = 4466
current_ts = 167.021408
current_received_ts = 167.020966
current_seq = 4467
count = 34
seq_step = 1
[165.253752] (kms_flip:4824) DEBUG: Vblank took 49us
[165.270368] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.021408
last_received_ts = 167.020966
last_seq = 4467
current_ts = 167.038086
current_received_ts = 167.037628
current_seq = 4468
count = 35
seq_step = 1
[165.270399] (kms_flip:4824) DEBUG: Vblank took 26us
[165.287034] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.038086
last_received_ts = 167.037628
last_seq = 4468
current_ts = 167.054749
current_received_ts = 167.054306
current_seq = 4469
count = 36
seq_step = 1
[165.287064] (kms_flip:4824) DEBUG: Vblank took 26us
[165.303700] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.054749
last_received_ts = 167.054306
last_seq = 4469
current_ts = 167.071411
current_received_ts = 167.070969
current_seq = 4470
count = 37
seq_step = 1
[165.303741] (kms_flip:4824) DEBUG: Vblank took 38us
[165.320372] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.071411
last_received_ts = 167.070969
last_seq = 4470
current_ts = 167.088074
current_received_ts = 167.087631
current_seq = 4471
count = 38
seq_step = 1
[165.320402] (kms_flip:4824) DEBUG: Vblank took 26us
[165.336887] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.088074
last_received_ts = 167.087631
last_seq = 4471
current_ts = 167.104752
current_received_ts = 167.104156
current_seq = 4472
count = 39
seq_step = 1
[165.336919] (kms_flip:4824) DEBUG: Vblank took 26us
[165.353554] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.104752
last_received_ts = 167.104156
last_seq = 4472
current_ts = 167.121414
current_received_ts = 167.120819
current_seq = 4473
count = 40
seq_step = 1
[165.353584] (kms_flip:4824) DEBUG: Vblank took 26us
[165.370255] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.121414
last_received_ts = 167.120819
last_seq = 4473
current_ts = 167.138077
current_received_ts = 167.137512
current_seq = 4474
count = 41
seq_step = 1
[165.370301] (kms_flip:4824) DEBUG: Vblank took 35us
[165.386927] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.138077
last_received_ts = 167.137512
last_seq = 4474
current_ts = 167.154755
current_received_ts = 167.154175
current_seq = 4475
count = 42
seq_step = 1
[165.386978] (kms_flip:4824) DEBUG: Vblank took 36us
[165.403581] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.154755
last_received_ts = 167.154175
last_seq = 4475
current_ts = 167.171417
current_received_ts = 167.170837
current_seq = 4476
count = 43
seq_step = 1
[165.403626] (kms_flip:4824) DEBUG: Vblank took 33us
[165.420246] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.171417
last_received_ts = 167.170837
last_seq = 4476
current_ts = 167.188080
current_received_ts = 167.187500
current_seq = 4477
count = 44
seq_step = 1
[165.420296] (kms_flip:4824) DEBUG: Vblank took 32us
[165.436916] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.188080
last_received_ts = 167.187500
last_seq = 4477
current_ts = 167.204742
current_received_ts = 167.204163
current_seq = 4478
count = 45
seq_step = 1
[165.436965] (kms_flip:4824) DEBUG: Vblank took 36us
[165.453563] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.204742
last_received_ts = 167.204163
last_seq = 4478
current_ts = 167.221420
current_received_ts = 167.220825
current_seq = 4479
count = 46
seq_step = 1
[165.453596] (kms_flip:4824) DEBUG: Vblank took 27us
[165.470231] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.221420
last_received_ts = 167.220825
last_seq = 4479
current_ts = 167.238083
current_received_ts = 167.237488
current_seq = 4480
count = 47
seq_step = 1
[165.470265] (kms_flip:4824) DEBUG: Vblank took 27us
[165.486894] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.238083
last_received_ts = 167.237488
last_seq = 4480
current_ts = 167.254745
current_received_ts = 167.254150
current_seq = 4481
count = 48
seq_step = 1
[165.486925] (kms_flip:4824) DEBUG: Vblank took 26us
[165.503563] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.254745
last_received_ts = 167.254150
last_seq = 4481
current_ts = 167.271423
current_received_ts = 167.270828
current_seq = 4482
count = 49
seq_step = 1
[165.503599] (kms_flip:4824) DEBUG: Vblank took 25us
[165.520230] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.271423
last_received_ts = 167.270828
last_seq = 4482
current_ts = 167.288086
current_received_ts = 167.287491
current_seq = 4483
count = 50
seq_step = 1
[165.520263] (kms_flip:4824) DEBUG: Vblank took 28us
[165.536897] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.288086
last_received_ts = 167.287491
last_seq = 4483
current_ts = 167.304749
current_received_ts = 167.304153
current_seq = 4484
count = 51
seq_step = 1
[165.536928] (kms_flip:4824) DEBUG: Vblank took 26us
[165.553714] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.304749
last_received_ts = 167.304153
last_seq = 4484
current_ts = 167.321411
current_received_ts = 167.320969
current_seq = 4485
count = 52
seq_step = 1
[165.553766] (kms_flip:4824) DEBUG: Vblank took 47us
[165.570341] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.321411
last_received_ts = 167.320969
last_seq = 4485
current_ts = 167.338089
current_received_ts = 167.337601
current_seq = 4486
count = 53
seq_step = 1
[165.570371] (kms_flip:4824) DEBUG: Vblank took 25us
[165.587048] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.338089
last_received_ts = 167.337601
last_seq = 4486
current_ts = 167.354752
current_received_ts = 167.354309
current_seq = 4487
count = 54
seq_step = 1
[165.587079] (kms_flip:4824) DEBUG: Vblank took 26us
[165.603715] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.354752
last_received_ts = 167.354309
last_seq = 4487
current_ts = 167.371414
current_received_ts = 167.370972
current_seq = 4488
count = 55
seq_step = 1
[165.603781] (kms_flip:4824) DEBUG: Vblank took 61us
[165.620383] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.371414
last_received_ts = 167.370972
last_seq = 4488
current_ts = 167.388092
current_received_ts = 167.387634
current_seq = 4489
count = 56
seq_step = 1
[165.620414] (kms_flip:4824) DEBUG: Vblank took 25us
[165.636901] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.388092
last_received_ts = 167.387634
last_seq = 4489
current_ts = 167.404755
current_received_ts = 167.404160
current_seq = 4490
count = 57
seq_step = 1
[165.636931] (kms_flip:4824) DEBUG: Vblank took 25us
[165.653568] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.404755
last_received_ts = 167.404160
last_seq = 4490
current_ts = 167.421417
current_received_ts = 167.420822
current_seq = 4491
count = 58
seq_step = 1
[165.653598] (kms_flip:4824) DEBUG: Vblank took 26us
[165.670238] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.421417
last_received_ts = 167.420822
last_seq = 4491
current_ts = 167.438080
current_received_ts = 167.437485
current_seq = 4492
count = 59
seq_step = 1
[165.670270] (kms_flip:4824) DEBUG: Vblank took 27us
[165.686903] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.438080
last_received_ts = 167.437485
last_seq = 4492
current_ts = 167.454758
current_received_ts = 167.454163
current_seq = 4493
count = 60
seq_step = 1
[165.686935] (kms_flip:4824) DEBUG: Vblank took 27us
[165.703571] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.454758
last_received_ts = 167.454163
last_seq = 4493
current_ts = 167.471420
current_received_ts = 167.470825
current_seq = 4494
count = 61
seq_step = 1
[165.703602] (kms_flip:4824) DEBUG: Vblank took 26us
[165.720239] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.471420
last_received_ts = 167.470825
last_seq = 4494
current_ts = 167.488083
current_received_ts = 167.487488
current_seq = 4495
count = 62
seq_step = 1
[165.720270] (kms_flip:4824) DEBUG: Vblank took 26us
[165.736906] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.488083
last_received_ts = 167.487488
last_seq = 4495
current_ts = 167.504745
current_received_ts = 167.504150
current_seq = 4496
count = 63
seq_step = 1
[165.736936] (kms_flip:4824) DEBUG: Vblank took 25us
[165.753574] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.504745
last_received_ts = 167.504150
last_seq = 4496
current_ts = 167.521423
current_received_ts = 167.520828
current_seq = 4497
count = 64
seq_step = 1
[165.753604] (kms_flip:4824) DEBUG: Vblank took 25us
[165.770241] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.521423
last_received_ts = 167.520828
last_seq = 4497
current_ts = 167.538086
current_received_ts = 167.537491
current_seq = 4498
count = 65
seq_step = 1
[165.770272] (kms_flip:4824) DEBUG: Vblank took 26us
[165.786909] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.538086
last_received_ts = 167.537491
last_seq = 4498
current_ts = 167.554749
current_received_ts = 167.554153
current_seq = 4499
count = 66
seq_step = 1
[165.786938] (kms_flip:4824) DEBUG: Vblank took 26us
[165.803576] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.554749
last_received_ts = 167.554153
last_seq = 4499
current_ts = 167.571426
current_received_ts = 167.570831
current_seq = 4500
count = 67
seq_step = 1
[165.803607] (kms_flip:4824) DEBUG: Vblank took 25us
[165.820243] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.571426
last_received_ts = 167.570831
last_seq = 4500
current_ts = 167.588089
current_received_ts = 167.587494
current_seq = 4501
count = 68
seq_step = 1
[165.820272] (kms_flip:4824) DEBUG: Vblank took 24us
[165.836911] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.588089
last_received_ts = 167.587494
last_seq = 4501
current_ts = 167.604752
current_received_ts = 167.604156
current_seq = 4502
count = 69
seq_step = 1
[165.836941] (kms_flip:4824) DEBUG: Vblank took 26us
[165.853728] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.604752
last_received_ts = 167.604156
last_seq = 4502
current_ts = 167.621429
current_received_ts = 167.620972
current_seq = 4503
count = 70
seq_step = 1
[165.853792] (kms_flip:4824) DEBUG: Vblank took 59us
[165.870396] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.621429
last_received_ts = 167.620972
last_seq = 4503
current_ts = 167.638092
current_received_ts = 167.637650
current_seq = 4504
count = 71
seq_step = 1
[165.870428] (kms_flip:4824) DEBUG: Vblank took 26us
[165.887063] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.638092
last_received_ts = 167.637650
last_seq = 4504
current_ts = 167.654755
current_received_ts = 167.654312
current_seq = 4505
count = 72
seq_step = 1
[165.887093] (kms_flip:4824) DEBUG: Vblank took 25us
[165.903688] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.654755
last_received_ts = 167.654312
last_seq = 4505
current_ts = 167.671432
current_received_ts = 167.670929
current_seq = 4506
count = 73
seq_step = 1
[165.903719] (kms_flip:4824) DEBUG: Vblank took 25us
[165.920358] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.671432
last_received_ts = 167.670929
last_seq = 4506
current_ts = 167.688095
current_received_ts = 167.687607
current_seq = 4507
count = 74
seq_step = 1
[165.920388] (kms_flip:4824) DEBUG: Vblank took 25us
[165.936916] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.688095
last_received_ts = 167.687607
last_seq = 4507
current_ts = 167.704758
current_received_ts = 167.704163
current_seq = 4508
count = 75
seq_step = 1
[165.936946] (kms_flip:4824) DEBUG: Vblank took 25us
[165.953583] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.704758
last_received_ts = 167.704163
last_seq = 4508
current_ts = 167.721420
current_received_ts = 167.720825
current_seq = 4509
count = 76
seq_step = 1
[165.953613] (kms_flip:4824) DEBUG: Vblank took 25us
[165.970267] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.721420
last_received_ts = 167.720825
last_seq = 4509
current_ts = 167.738083
current_received_ts = 167.737518
current_seq = 4510
count = 77
seq_step = 1
[165.970297] (kms_flip:4824) DEBUG: Vblank took 26us
[165.986919] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.738083
last_received_ts = 167.737518
last_seq = 4510
current_ts = 167.754761
current_received_ts = 167.754166
current_seq = 4511
count = 78
seq_step = 1
[165.986950] (kms_flip:4824) DEBUG: Vblank took 25us
[166.003586] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.754761
last_received_ts = 167.754166
last_seq = 4511
current_ts = 167.771423
current_received_ts = 167.770828
current_seq = 4512
count = 79
seq_step = 1
[166.003617] (kms_flip:4824) DEBUG: Vblank took 26us
[166.020253] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.771423
last_received_ts = 167.770828
last_seq = 4512
current_ts = 167.788086
current_received_ts = 167.787491
current_seq = 4513
count = 80
seq_step = 1
[166.020285] (kms_flip:4824) DEBUG: Vblank took 26us
[166.036922] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.788086
last_received_ts = 167.787491
last_seq = 4513
current_ts = 167.804764
current_received_ts = 167.804169
current_seq = 4514
count = 81
seq_step = 1
[166.036953] (kms_flip:4824) DEBUG: Vblank took 25us
[166.053590] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.804764
last_received_ts = 167.804169
last_seq = 4514
current_ts = 167.821426
current_received_ts = 167.820831
current_seq = 4515
count = 82
seq_step = 1
[166.053620] (kms_flip:4824) DEBUG: Vblank took 26us
[166.070257] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.821426
last_received_ts = 167.820831
last_seq = 4515
current_ts = 167.838089
current_received_ts = 167.837494
current_seq = 4516
count = 83
seq_step = 1
[166.070288] (kms_flip:4824) DEBUG: Vblank took 26us
[166.086925] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.838089
last_received_ts = 167.837494
last_seq = 4516
current_ts = 167.854752
current_received_ts = 167.854172
current_seq = 4517
count = 84
seq_step = 1
[166.086956] (kms_flip:4824) DEBUG: Vblank took 26us
[166.103590] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.854752
last_received_ts = 167.854172
last_seq = 4517
current_ts = 167.871429
current_received_ts = 167.870834
current_seq = 4518
count = 85
seq_step = 1
[166.103621] (kms_flip:4824) DEBUG: Vblank took 26us
[166.120260] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.871429
last_received_ts = 167.870834
last_seq = 4518
current_ts = 167.888092
current_received_ts = 167.887497
current_seq = 4519
count = 86
seq_step = 1
[166.120291] (kms_flip:4824) DEBUG: Vblank took 26us
[166.136926] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.888092
last_received_ts = 167.887497
last_seq = 4519
current_ts = 167.904755
current_received_ts = 167.904160
current_seq = 4520
count = 87
seq_step = 1
[166.136956] (kms_flip:4824) DEBUG: Vblank took 24us
[166.153743] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.904755
last_received_ts = 167.904160
last_seq = 4520
current_ts = 167.921432
current_received_ts = 167.920975
current_seq = 4521
count = 88
seq_step = 1
[166.153792] (kms_flip:4824) DEBUG: Vblank took 43us
[166.170410] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.921432
last_received_ts = 167.920975
last_seq = 4521
current_ts = 167.938095
current_received_ts = 167.937653
current_seq = 4522
count = 89
seq_step = 1
[166.170440] (kms_flip:4824) DEBUG: Vblank took 25us
[166.187037] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.938095
last_received_ts = 167.937653
last_seq = 4522
current_ts = 167.954758
current_received_ts = 167.954269
current_seq = 4523
count = 90
seq_step = 1
[166.187067] (kms_flip:4824) DEBUG: Vblank took 26us
[166.203745] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.954758
last_received_ts = 167.954269
last_seq = 4523
current_ts = 167.971436
current_received_ts = 167.970978
current_seq = 4524
count = 91
seq_step = 1
[166.203792] (kms_flip:4824) DEBUG: Vblank took 42us
[166.220413] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.971436
last_received_ts = 167.970978
last_seq = 4524
current_ts = 167.988098
current_received_ts = 167.987656
current_seq = 4525
count = 92
seq_step = 1
[166.220443] (kms_flip:4824) DEBUG: Vblank took 25us
[166.236932] (kms_flip:4824) DEBUG: name = flip
last_ts = 167.988098
last_received_ts = 167.987656
last_seq = 4525
current_ts = 168.004761
current_received_ts = 168.004166
current_seq = 4526
count = 93
seq_step = 1
[166.236962] (kms_flip:4824) DEBUG: Vblank took 25us
[166.253598] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.004761
last_received_ts = 168.004166
last_seq = 4526
current_ts = 168.021423
current_received_ts = 168.020828
current_seq = 4527
count = 94
seq_step = 1
[166.253628] (kms_flip:4824) DEBUG: Vblank took 25us
[166.270267] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.021423
last_received_ts = 168.020828
last_seq = 4527
current_ts = 168.038101
current_received_ts = 168.037506
current_seq = 4528
count = 95
seq_step = 1
[166.270298] (kms_flip:4824) DEBUG: Vblank took 26us
[166.286935] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.038101
last_received_ts = 168.037506
last_seq = 4528
current_ts = 168.054764
current_received_ts = 168.054169
current_seq = 4529
count = 96
seq_step = 1
[166.286966] (kms_flip:4824) DEBUG: Vblank took 27us
[166.303600] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.054764
last_received_ts = 168.054169
last_seq = 4529
current_ts = 168.071426
current_received_ts = 168.070831
current_seq = 4530
count = 97
seq_step = 1
[166.303631] (kms_flip:4824) DEBUG: Vblank took 26us
[166.320268] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.071426
last_received_ts = 168.070831
last_seq = 4530
current_ts = 168.088104
current_received_ts = 168.087509
current_seq = 4531
count = 98
seq_step = 1
[166.320299] (kms_flip:4824) DEBUG: Vblank took 26us
[166.337052] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.088104
last_received_ts = 168.087509
last_seq = 4531
current_ts = 168.104767
current_received_ts = 168.104263
current_seq = 4532
count = 99
seq_step = 1
[166.337103] (kms_flip:4824) DEBUG: Vblank took 37us
[166.353619] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.104767
last_received_ts = 168.104263
last_seq = 4532
current_ts = 168.121429
current_received_ts = 168.120834
current_seq = 4533
count = 100
seq_step = 1
[166.353671] (kms_flip:4824) DEBUG: Vblank took 37us
[166.370396] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.121429
last_received_ts = 168.120834
last_seq = 4533
current_ts = 168.138092
current_received_ts = 168.137619
current_seq = 4534
count = 101
seq_step = 1
[166.370447] (kms_flip:4824) DEBUG: Vblank took 37us
[166.386957] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.138092
last_received_ts = 168.137619
last_seq = 4534
current_ts = 168.154770
current_received_ts = 168.154175
current_seq = 4535
count = 102
seq_step = 1
[166.387008] (kms_flip:4824) DEBUG: Vblank took 37us
[166.403610] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.154770
last_received_ts = 168.154175
last_seq = 4535
current_ts = 168.171432
current_received_ts = 168.170837
current_seq = 4536
count = 103
seq_step = 1
[166.403644] (kms_flip:4824) DEBUG: Vblank took 28us
[166.420282] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.171432
last_received_ts = 168.170837
last_seq = 4536
current_ts = 168.188095
current_received_ts = 168.187515
current_seq = 4537
count = 104
seq_step = 1
[166.420316] (kms_flip:4824) DEBUG: Vblank took 27us
[166.436943] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.188095
last_received_ts = 168.187515
last_seq = 4537
current_ts = 168.204773
current_received_ts = 168.204178
current_seq = 4538
count = 105
seq_step = 1
[166.436973] (kms_flip:4824) DEBUG: Vblank took 25us
[166.453758] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.204773
last_received_ts = 168.204178
last_seq = 4538
current_ts = 168.221436
current_received_ts = 168.220993
current_seq = 4539
count = 106
seq_step = 1
[166.453809] (kms_flip:4824) DEBUG: Vblank took 47us
[166.470429] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.221436
last_received_ts = 168.220993
last_seq = 4539
current_ts = 168.238098
current_received_ts = 168.237656
current_seq = 4540
count = 107
seq_step = 1
[166.470461] (kms_flip:4824) DEBUG: Vblank took 26us
[166.487095] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.238098
last_received_ts = 168.237656
last_seq = 4540
current_ts = 168.254776
current_received_ts = 168.254318
current_seq = 4541
count = 108
seq_step = 1
[166.487126] (kms_flip:4824) DEBUG: Vblank took 26us
[166.503719] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.254776
last_received_ts = 168.254318
last_seq = 4541
current_ts = 168.271439
current_received_ts = 168.270950
current_seq = 4542
count = 109
seq_step = 1
[166.503750] (kms_flip:4824) DEBUG: Vblank took 26us
[166.520431] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.271439
last_received_ts = 168.270950
last_seq = 4542
current_ts = 168.288101
current_received_ts = 168.287659
current_seq = 4543
count = 110
seq_step = 1
[166.520462] (kms_flip:4824) DEBUG: Vblank took 26us
[166.536947] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.288101
last_received_ts = 168.287659
last_seq = 4543
current_ts = 168.304764
current_received_ts = 168.304169
current_seq = 4544
count = 111
seq_step = 1
[166.536978] (kms_flip:4824) DEBUG: Vblank took 25us
[166.553613] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.304764
last_received_ts = 168.304169
last_seq = 4544
current_ts = 168.321442
current_received_ts = 168.320847
current_seq = 4545
count = 112
seq_step = 1
[166.553644] (kms_flip:4824) DEBUG: Vblank took 26us
[166.570282] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.321442
last_received_ts = 168.320847
last_seq = 4545
current_ts = 168.338104
current_received_ts = 168.337509
current_seq = 4546
count = 113
seq_step = 1
[166.570312] (kms_flip:4824) DEBUG: Vblank took 25us
[166.586949] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.338104
last_received_ts = 168.337509
last_seq = 4546
current_ts = 168.354767
current_received_ts = 168.354172
current_seq = 4547
count = 114
seq_step = 1
[166.586981] (kms_flip:4824) DEBUG: Vblank took 27us
[166.603615] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.354767
last_received_ts = 168.354172
last_seq = 4547
current_ts = 168.371429
current_received_ts = 168.370834
current_seq = 4548
count = 115
seq_step = 1
[166.603646] (kms_flip:4824) DEBUG: Vblank took 25us
[166.620283] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.371429
last_received_ts = 168.370834
last_seq = 4548
current_ts = 168.388107
current_received_ts = 168.387512
current_seq = 4549
count = 116
seq_step = 1
[166.620314] (kms_flip:4824) DEBUG: Vblank took 26us
[166.636951] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.388107
last_received_ts = 168.387512
last_seq = 4549
current_ts = 168.404770
current_received_ts = 168.404175
current_seq = 4550
count = 117
seq_step = 1
[166.636981] (kms_flip:4824) DEBUG: Vblank took 25us
[166.653670] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.404770
last_received_ts = 168.404175
last_seq = 4550
current_ts = 168.421432
current_received_ts = 168.420883
current_seq = 4551
count = 118
seq_step = 1
[166.653731] (kms_flip:4824) DEBUG: Vblank took 44us
[166.670307] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.421432
last_received_ts = 168.420883
last_seq = 4551
current_ts = 168.438110
current_received_ts = 168.437515
current_seq = 4552
count = 119
seq_step = 1
[166.670358] (kms_flip:4824) DEBUG: Vblank took 38us
[166.686959] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.438110
last_received_ts = 168.437515
last_seq = 4552
current_ts = 168.454773
current_received_ts = 168.454178
current_seq = 4553
count = 120
seq_step = 1
[166.686995] (kms_flip:4824) DEBUG: Vblank took 28us
[166.703624] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.454773
last_received_ts = 168.454178
last_seq = 4553
current_ts = 168.471436
current_received_ts = 168.470840
current_seq = 4554
count = 121
seq_step = 1
[166.703657] (kms_flip:4824) DEBUG: Vblank took 26us
[166.720291] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.471436
last_received_ts = 168.470840
last_seq = 4554
current_ts = 168.488113
current_received_ts = 168.487518
current_seq = 4555
count = 122
seq_step = 1
[166.720322] (kms_flip:4824) DEBUG: Vblank took 26us
[166.736960] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.488113
last_received_ts = 168.487518
last_seq = 4555
current_ts = 168.504776
current_received_ts = 168.504181
current_seq = 4556
count = 123
seq_step = 1
[166.736991] (kms_flip:4824) DEBUG: Vblank took 26us
[166.753751] (kms_flip:4824) DEBUG: name = flip
last_ts = 168.504776
last_received_ts = 168.504181
last_seq = 4556
current_ts = 168.521439
current_received_ts = 168.520950
current_seq = 4557
count = 124
seq_step = 1
[166.754665] (kms_flip:4824) DEBUG: Vblank took 899us
[166.754674] (kms_flip:4824) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[166.754752] (kms_flip:4824) CRITICAL: Failed assertion: end - start < 500
[166.754831] (kms_flip:4824) CRITICAL: Last errno: 4, Interrupted system call
[166.760857] (kms_flip:4824) igt_core-INFO: Stack trace:
[166.774693] (kms_flip:4824) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[166.775942] (kms_flip:4824) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[166.776009] (kms_flip:4824) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
[166.776058] (kms_flip:4824) igt_core-INFO:   #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
[166.776088] (kms_flip:4824) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[166.779412] (kms_flip:4824) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[166.780273] (kms_flip:4824) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[166.780495] (kms_flip:4824) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest BC-DP2-HDMI-A3: FAIL (2.943s)
Dmesg
<6> [165.843273] [IGT] kms_flip: starting dynamic subtest BC-DP2-HDMI-A3
<7> [165.843794] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:559]
<7> [165.844134] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [165.885868] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [165.885956] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [165.886092] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [165.886203] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [165.886308] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [165.886407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7208960/8388608 link 480597/524288, found tu 0, data 0/0 link 0/0)
<7> [165.886507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [165.886605] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [165.886703] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [165.886800] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [165.886897] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [165.887009] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [165.887106] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [165.887202] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [165.887296] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [165.887391] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [165.887484] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [165.887579] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [165.887672] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [165.887767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [165.887862] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [165.887967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [165.888061] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [165.888156] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [165.888251] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [165.888345] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [165.888439] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [165.888531] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [165.888626] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [165.888720] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [165.888813] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [165.888914] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [165.889007] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [165.889100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [165.889194] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [165.889289] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [165.889382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [165.889477] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [165.889572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [165.889666] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 162000, found 0)
<7> [165.889760] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [165.889855] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [165.889982] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 1024) -> (0 - 0), active pipes 0x9 -> 0x8
<7> [165.890096] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x3, ddb (1024 - 2048) -> (0 - 2048), active pipes 0x9 -> 0x8
<7> [165.890215] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 965 - 1024) -> ( 0 - 0), size 59 -> 0
<7> [165.890310] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [165.890445] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [165.890553] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [165.890657] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [165.890760] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [165.890861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [165.890972] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [165.891072] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [165.891831] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [165.918410] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [165.919066] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [165.936171] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [165.938710] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [165.941281] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [165.942019] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [165.942487] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [165.942902] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<6> [165.943451] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [165.943216] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [165.943387] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [165.943812] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [165.944245] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [165.944691] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [165.945051] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [165.945366] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [165.945669] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [165.945979] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [165.946261] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [165.946532] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [165.946800] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [165.947076] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [165.947324] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [165.947559] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [165.949535] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [165.953363] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [165.953889] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [165.954435] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [165.955199] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [165.955468] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [165.955670] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [165.955786] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [165.956103] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [165.956350] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [165.956577] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [165.956786] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [165.957011] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [165.957231] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [165.957427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [165.957614] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [165.957790] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [165.957973] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [165.958145] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [165.958312] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [165.958481] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [165.958639] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [165.958798] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [165.958963] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [165.959113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [165.959259] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [165.959399] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [165.959537] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [165.959673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [165.959808] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [165.959953] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [165.960079] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [165.960204] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [165.960326] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [165.960445] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [165.960563] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [165.960682] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [165.960795] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [165.960915] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [165.961030] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [165.961141] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [165.961248] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [165.961356] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [165.961462] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [165.961568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [165.961667] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [165.961767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [165.961868] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [165.961978] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [165.962083] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [165.962179] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [165.962181] xe 0000:03:00.0: colorspace: RGB
<7> [165.962182] xe 0000:03:00.0: scan mode: Underscan
<7> [165.962183] xe 0000:03:00.0: colorimetry: No Data
<7> [165.962185] xe 0000:03:00.0: picture aspect: 16:9
<7> [165.962186] xe 0000:03:00.0: active aspect: Same as Picture
<7> [165.962187] xe 0000:03:00.0: itc: No Data
<7> [165.962188] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [165.962189] xe 0000:03:00.0: quantization range: Default
<7> [165.962191] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [165.962192] xe 0000:03:00.0: video code: 16
<7> [165.962193] xe 0000:03:00.0: ycc quantization range: Full
<7> [165.962194] xe 0000:03:00.0: hdmi content type: Graphics
<7> [165.962196] xe 0000:03:00.0: pixel repeat: 0
<7> [165.962197] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [165.962199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [165.962294] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [165.962389] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [165.962483] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [165.962485] xe 0000:03:00.0: vendor: Intel
<7> [165.962486] xe 0000:03:00.0: product: Discrete gfx
<7> [165.962487] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [165.962489] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [165.962583] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [165.962676] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [165.962770] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [165.962771] xe 0000:03:00.0: empty frame
<7> [165.962772] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [165.962866] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [165.962968] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:505:pipe D] releasing TC PLL 3
<7> [165.963078] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [165.963161] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [165.963259] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:499:cursor D] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [165.963347] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [165.963477] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: no [modeset]
<7> [165.963582] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [165.963685] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [165.963785] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [165.963885] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [165.964000] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [165.964100] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [165.964770] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:505:pipe D]
<7> [165.986794] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [165.987475] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe D
<7> [166.000248] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [166.005172] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x8, on? 1) for [CRTC:505:pipe D]
<7> [166.007666] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [166.008551] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [166.009069] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [166.009527] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [166.010005] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [166.010429] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [166.010859] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [166.011332] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [166.011735] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [166.012139] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [166.012514] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [166.012886] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [166.013469] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [166.013801] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [166.014173] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [166.014491] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [166.014796] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [166.015121] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [166.015480] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [166.015944] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [166.016537] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [166.017048] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [166.017554] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [166.018057] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [166.018707] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [166.018761] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [166.018902] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [166.019132] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [166.019358] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:387:pipe B]
<7> [166.019579] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [166.019792] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [166.019998] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [166.020182] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [166.020365] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [166.020537] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [166.020702] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [166.020865] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [166.021039] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [166.021196] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [166.021354] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [166.021503] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [166.021647] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [166.021791] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [166.021947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [166.022086] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [166.022219] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [166.022348] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [166.022476] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [166.022599] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [166.022722] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [166.022845] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [166.022978] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [166.023091] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [166.023204] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [166.023312] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [166.023422] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [166.023527] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [166.023634] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [166.023734] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [166.023835] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [166.023943] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [166.024043] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [166.024143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [166.024236] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [166.024329] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [166.024423] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [166.024516] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [166.024608] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [166.024700] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [166.024793] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [166.024886] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [166.024985] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [166.025078] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [166.025169] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [166.025171] xe 0000:03:00.0: colorspace: RGB
<7> [166.025173] xe 0000:03:00.0: scan mode: Underscan
<7> [166.025174] xe 0000:03:00.0: colorimetry: No Data
<7> [166.025175] xe 0000:03:00.0: picture aspect: 16:9
<7> [166.025177] xe 0000:03:00.0: active aspect: Same as Picture
<7> [166.025178] xe 0000:03:00.0: itc: No Data
<7> [166.025179] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [166.025180] xe 0000:03:00.0: quantization range: Default
<7> [166.025181] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [166.025183] xe 0000:03:00.0: video code: 16
<7> [166.025184] xe 0000:03:00.0: ycc quantization range: Full
<7> [166.025185] xe 0000:03:00.0: hdmi content type: Graphics
<7> [166.025186] xe 0000:03:00.0: pixel repeat: 0
<7> [166.025188] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [166.025189] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [166.025290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [166.025385] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [166.025480] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [166.025481] xe 0000:03:00.0: vendor: Intel
<7> [166.025482] xe 0000:03:00.0: product: Discrete gfx
<7> [166.025484] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [166.025485] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [166.025579] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [166.025672] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [166.025765] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [166.025766] xe 0000:03:00.0: empty frame
<7> [166.025768] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [166.025891] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [166.025990] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [166.026088] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [166.026164] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [166.026237] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [166.026310] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [166.026383] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [166.026454] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] 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> [166.026538] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe B] data rate 594000 num active planes 1
<7> [166.026643] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [166.026748] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [166.026851] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [166.026962] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [166.027087] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe B] allocated TC PLL 3
<7> [166.027186] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe B] reserving TC PLL 3
<7> [166.027281] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: yes [modeset]
<7> [166.027386] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [166.027491] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [166.027593] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [166.027693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [166.027793] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [166.027893] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [166.027999] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [166.028097] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [166.028194] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [166.028196] xe 0000:03:00.0: colorspace: RGB
<7> [166.028197] xe 0000:03:00.0: scan mode: Underscan
<7> [166.028198] xe 0000:03:00.0: colorimetry: No Data
<7> [166.028199] xe 0000:03:00.0: picture aspect: 16:9
<7> [166.028201] xe 0000:03:00.0: active aspect: Same as Picture
<7> [166.028202] xe 0000:03:00.0: itc: No Data
<7> [166.028203] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [166.028204] xe 0000:03:00.0: quantization range: Default
<7> [166.028205] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [166.028207] xe 0000:03:00.0: video code: 16
<7> [166.028208] xe 0000:03:00.0: ycc quantization range: Full
<7> [166.028209] xe 0000:03:00.0: hdmi content type: Graphics
<7> [166.028210] xe 0000:03:00.0: pixel repeat: 0
<7> [166.028212] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [166.028213] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [166.028215] xe 0000:03:00.0: vendor: Intel
<7> [166.028216] xe 0000:03:00.0: product: Discrete gfx
<7> [166.028217] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [166.028218] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [166.028220] xe 0000:03:00.0: empty frame
<7> [166.028221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [166.028319] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [166.028417] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [166.028515] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [166.028613] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [166.028710] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [166.028809] 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> [166.028906] 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> [166.029015] 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> [166.029113] 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> [166.029209] 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> [166.029308] 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> [166.029407] 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> [166.029505] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [166.029602] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [166.029699] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [166.029797] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [166.029894] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [166.030006] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [166.030101] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [166.030198] 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> [166.030295] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [166.030392] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [166.030489] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [166.030591] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [166.030692] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [166.030790] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [166.030887] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [166.030989] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [166.031083] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [166.031178] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [166.031272] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [166.031366] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [166.031459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [166.031553] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [166.031647] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [166.031741] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [166.031835] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [166.031932] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [166.032026] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032120] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032214] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032306] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [166.032401] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [166.032495] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032589] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032683] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.032777] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [166.032870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [166.032973] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [166.033065] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [166.033158] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [166.033250] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [166.033351] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [166.033451] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [166.033549] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [166.034612] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [166.034793] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [166.035184] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [166.035348] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [166.035477] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [166.035602] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [166.035725] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [166.035847] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [166.035977] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [166.036101] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [166.036223] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [166.036346] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [166.036468] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [166.036590] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [166.036713] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [166.036835] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [166.036962] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [166.037086] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [166.037208] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [166.037543] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [166.037756] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [166.038073] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [166.038212] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:387:pipe B]
<7> [166.038348] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [166.039978] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [166.041756] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [166.077621] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:387:pipe B], 36 bytes ELD
<7> [166.094084] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [166.094720] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [166.095775] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [166.096565] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [166.101248] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [166.101271] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [166.101341] xe 0000:03:00.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:527:DP-2] Limiting target display pipe bpp to 30 (EDID bpp 48, max requested bpp 30, max platform bpp 36)
<7> [166.101455] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:269:pipe C] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [166.101557] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 162000 bpp input 30 compressed 0.0000 HDR no link rate required 556875 available 648000
<7> [166.101659] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [166.101763] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:269:pipe C]
<7> [166.101870] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [166.101984] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [166.102086] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [166.102191] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [166.102294] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7208960/8388608 link 480597/524288)
<7> [166.102394] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [166.102492] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [166.102589] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [166.102686] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [166.102783] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [166.102878] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [166.102980] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [166.103076] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [166.103169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [166.103229] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [166.103262] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [166.103356] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [166.103449] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [166.103544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [166.103639] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [166.103731] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [166.103825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [166.103927] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [166.104023] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [166.104117] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [166.104212] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [166.104332] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [166.104451] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [166.104563] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [166.104657] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [166.104752] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [166.104847] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [166.104964] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [166.105059] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [166.105154] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [166.105249] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [166.105345] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in dpll_hw_state
<7> [166.105440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [166.105535] 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> [166.105630] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [166.105724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [166.105821] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [166.105926] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [166.106023] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [166.106118] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [166.106213] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [166.106307] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [166.106402] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [166.106497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [166.106591] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [166.106686] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [166.106780] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [166.106875] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [166.106979] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [166.107075] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [166.107170] 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> [166.107265] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [166.107360] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [166.107456] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [166.107551] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x50a8
<7> [166.107646] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x2120
<7> [166.107741] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0xcd9a
<7> [166.107836] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0xbfc1
<7> [166.107936] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x5ab8
<7> [166.108032] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x4c34
<7> [166.108127] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2000
<7> [166.108221] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0x0001
<7> [166.108317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x6000
<7> [166.108411] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [166.108506] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [166.108601] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00
<7> [166.108696] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [166.108792] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [166.108888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [166.109016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 0, found 162000)
<7> [166.109113] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [166.109238] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xd (total dbuf slices 0xf), mbus joined? yes->no
<7> [166.109329] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0xc, ddb (0 - 4096) -> (0 - 1024), active pipes 0x2 -> 0x6
<7> [166.109409] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (1024 - 2048), active pipes 0x2 -> 0x6
<7> [166.109515] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 4037) -> ( 0 - 965), size 4037 -> 965
<7> [166.109594] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> ( 965 - 1024), size 59 -> 59
<7> [166.109670] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:153:plane 1C] ddb ( 0 - 0) -> (1024 - 1989), size 0 -> 965
<7> [166.109746] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [166.109820] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153: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> [166.109892] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153: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> [166.109981] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153: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> [166.110053] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153: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> [166.110136] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:269:pipe C] data rate 594000 num active planes 1
<7> [166.110250] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [166.110355] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [166.110455] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [166.110555] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [166.110680] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:269:pipe C] allocated TC PLL 2
<7> [166.110778] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:269:pipe C] reserving TC PLL 2
<7> [166.110905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: yes [modeset]
<7> [166.111047] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [166.111151] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [166.111253] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [166.111353] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [166.111453] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [166.111552] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [166.111650] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 7208960, data_n: 8388608, link_m: 480597, link_n: 524288, tu: 64
<7> [166.111748] 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> [166.111846] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [166.111948] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [166.112051] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [166.112152] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [166.112251] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [166.112349] 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> [166.112446] 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> [166.112542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [166.112638] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [166.112734] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [166.112830] 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> [166.112932] 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> [166.113028] 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> [166.113125] 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> [166.113221] 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> [166.113316] 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> [166.113412] 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> [166.113508] 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> [166.113603] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [166.113698] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [166.113793] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [166.113887] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [166.113992] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [166.114088] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [166.114183] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [166.114278] 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> [166.114372] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [166.114466] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [166.114561] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [166.114655] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x50a8
<7> [166.114749] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2120
<7> [166.114843] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcd9a
<7> [166.114942] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [166.115036] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x5ab8
<7> [166.115129] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x4c34
<7> [166.115223] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [166.115317] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [166.115411] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x6000
<7> [166.115504] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [166.115597] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [166.115691] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00
<7> [166.115785] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [166.115880] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [166.115984] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [166.116078] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116176] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116270] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116364] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [166.116458] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [166.116552] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116647] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116741] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [166.116835] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [166.116934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [166.117030] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [166.117125] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [166.117221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [166.117315] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [166.117409] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [166.117502] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [166.117596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [166.118478] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [166.118605] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x82000000)
<7> [166.118717] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [166.118809] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [166.118897] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [166.118997] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [166.119081] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [166.119163] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [166.119246] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [166.119329] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [166.119411] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [166.119492] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [166.119574] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [166.119656] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [166.119737] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [166.119817] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [166.119898] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [166.119991] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [166.127073] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [166.127381] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: B)
<7> [166.143735] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x4, on? 0) for [CRTC:269:pipe C]
<7> [166.143848] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [166.146748] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [166.151535] 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> [166.158676] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [166.160128] 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> [166.160855] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 06
<7> [166.162735] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [166.163602] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [166.169985] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [166.170426] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [166.176831] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [166.177260] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 162000, lane count = 4
<7> [166.179169] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [166.213413] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [166.214587] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:269:pipe C], 40 bytes ELD
<7> [166.230438] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [166.231321] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [166.252433] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [166.279994] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [168.224156] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [168.328029] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [168.796243] [IGT] kms_flip: finished subtest BC-DP2-HDMI-A3, FAIL
Created at 2026-05-15 06:48:22