Results for igt@kms_flip@flip-vs-expired-vblank@c-dp4

Machine description: shard-dg2-436

Result: Fail

i915_display_info20 igt_runner20 results20.json results20-xe-load.json boot20 dmesg20

DetailValue
Duration 3.14 seconds
Hostname 
shard-dg2-436
Igt-Version 
IGT-Version: 2.0-g534d75199 (x86_64) (Linux: 6.14.0-rc6-xe+ x86_64)
Out 
Starting dynamic subtest: C-DP4
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:905 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1831 run_test()
  #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
  #4 ../tests/kms_flip.c:1987 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-DP4: FAIL (3.136s)
Err 
Starting dynamic subtest: C-DP4
(kms_flip:2961) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:910:
(kms_flip:2961) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-DP4 failed.
**** DEBUG ****
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.829857
last_received_ts = 108.829254
last_seq = 311
current_ts = 108.846542
current_received_ts = 108.845932
current_seq = 312
count = 32
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.846542
last_received_ts = 108.845932
last_seq = 312
current_ts = 108.863220
current_received_ts = 108.862617
current_seq = 313
count = 33
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.863220
last_received_ts = 108.862617
last_seq = 313
current_ts = 108.879906
current_received_ts = 108.879295
current_seq = 314
count = 34
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.879906
last_received_ts = 108.879295
last_seq = 314
current_ts = 108.896584
current_received_ts = 108.895981
current_seq = 315
count = 35
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 42us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.896584
last_received_ts = 108.895981
last_seq = 315
current_ts = 108.913269
current_received_ts = 108.912666
current_seq = 316
count = 36
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.913269
last_received_ts = 108.912666
last_seq = 316
current_ts = 108.929955
current_received_ts = 108.929344
current_seq = 317
count = 37
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.929955
last_received_ts = 108.929344
last_seq = 317
current_ts = 108.946632
current_received_ts = 108.946037
current_seq = 318
count = 38
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 22us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.946632
last_received_ts = 108.946037
last_seq = 318
current_ts = 108.963318
current_received_ts = 108.962708
current_seq = 319
count = 39
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.963318
last_received_ts = 108.962708
last_seq = 319
current_ts = 108.979996
current_received_ts = 108.979393
current_seq = 320
count = 40
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.979996
last_received_ts = 108.979393
last_seq = 320
current_ts = 108.996674
current_received_ts = 108.996078
current_seq = 321
count = 41
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 108.996674
last_received_ts = 108.996078
last_seq = 321
current_ts = 109.013359
current_received_ts = 109.012756
current_seq = 322
count = 42
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.013359
last_received_ts = 109.012756
last_seq = 322
current_ts = 109.030045
current_received_ts = 109.029442
current_seq = 323
count = 43
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.030045
last_received_ts = 109.029442
last_seq = 323
current_ts = 109.046722
current_received_ts = 109.046173
current_seq = 324
count = 44
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 409us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.046722
last_received_ts = 109.046173
last_seq = 324
current_ts = 109.063408
current_received_ts = 109.062843
current_seq = 325
count = 45
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 443us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.063408
last_received_ts = 109.062843
last_seq = 325
current_ts = 109.080086
current_received_ts = 109.079498
current_seq = 326
count = 46
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 27us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.080086
last_received_ts = 109.079498
last_seq = 326
current_ts = 109.096771
current_received_ts = 109.096169
current_seq = 327
count = 47
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.096771
last_received_ts = 109.096169
last_seq = 327
current_ts = 109.113457
current_received_ts = 109.112846
current_seq = 328
count = 48
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.113457
last_received_ts = 109.112846
last_seq = 328
current_ts = 109.130135
current_received_ts = 109.129532
current_seq = 329
count = 49
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.130135
last_received_ts = 109.129532
last_seq = 329
current_ts = 109.146820
current_received_ts = 109.146217
current_seq = 330
count = 50
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.146820
last_received_ts = 109.146217
last_seq = 330
current_ts = 109.163498
current_received_ts = 109.162895
current_seq = 331
count = 51
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.163498
last_received_ts = 109.162895
last_seq = 331
current_ts = 109.180183
current_received_ts = 109.179581
current_seq = 332
count = 52
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.180183
last_received_ts = 109.179581
last_seq = 332
current_ts = 109.196861
current_received_ts = 109.196259
current_seq = 333
count = 53
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.196861
last_received_ts = 109.196259
last_seq = 333
current_ts = 109.213547
current_received_ts = 109.212944
current_seq = 334
count = 54
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.213547
last_received_ts = 109.212944
last_seq = 334
current_ts = 109.230225
current_received_ts = 109.229622
current_seq = 335
count = 55
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.230225
last_received_ts = 109.229622
last_seq = 335
current_ts = 109.246910
current_received_ts = 109.246307
current_seq = 336
count = 56
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.246910
last_received_ts = 109.246307
last_seq = 336
current_ts = 109.263588
current_received_ts = 109.262985
current_seq = 337
count = 57
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 52us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.263588
last_received_ts = 109.262985
last_seq = 337
current_ts = 109.280273
current_received_ts = 109.279671
current_seq = 338
count = 58
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.280273
last_received_ts = 109.279671
last_seq = 338
current_ts = 109.296959
current_received_ts = 109.296349
current_seq = 339
count = 59
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.296959
last_received_ts = 109.296349
last_seq = 339
current_ts = 109.313637
current_received_ts = 109.313049
current_seq = 340
count = 60
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.313637
last_received_ts = 109.313049
last_seq = 340
current_ts = 109.330307
current_received_ts = 109.329727
current_seq = 341
count = 61
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.330307
last_received_ts = 109.329727
last_seq = 341
current_ts = 109.347000
current_received_ts = 109.346413
current_seq = 342
count = 62
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.347000
last_received_ts = 109.346413
last_seq = 342
current_ts = 109.363670
current_received_ts = 109.363083
current_seq = 343
count = 63
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.363670
last_received_ts = 109.363083
last_seq = 343
current_ts = 109.380363
current_received_ts = 109.379761
current_seq = 344
count = 64
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.380363
last_received_ts = 109.379761
last_seq = 344
current_ts = 109.397041
current_received_ts = 109.396446
current_seq = 345
count = 65
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.397041
last_received_ts = 109.396446
last_seq = 345
current_ts = 109.413727
current_received_ts = 109.413124
current_seq = 346
count = 66
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.413727
last_received_ts = 109.413124
last_seq = 346
current_ts = 109.430405
current_received_ts = 109.429802
current_seq = 347
count = 67
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.430405
last_received_ts = 109.429802
last_seq = 347
current_ts = 109.447090
current_received_ts = 109.446480
current_seq = 348
count = 68
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.447090
last_received_ts = 109.446480
last_seq = 348
current_ts = 109.463768
current_received_ts = 109.463165
current_seq = 349
count = 69
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.463768
last_received_ts = 109.463165
last_seq = 349
current_ts = 109.480453
current_received_ts = 109.479843
current_seq = 350
count = 70
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.480453
last_received_ts = 109.479843
last_seq = 350
current_ts = 109.497131
current_received_ts = 109.496529
current_seq = 351
count = 71
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.497131
last_received_ts = 109.496529
last_seq = 351
current_ts = 109.513817
current_received_ts = 109.513206
current_seq = 352
count = 72
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.513817
last_received_ts = 109.513206
last_seq = 352
current_ts = 109.530495
current_received_ts = 109.529892
current_seq = 353
count = 73
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.530495
last_received_ts = 109.529892
last_seq = 353
current_ts = 109.547173
current_received_ts = 109.546570
current_seq = 354
count = 74
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.547173
last_received_ts = 109.546570
last_seq = 354
current_ts = 109.563858
current_received_ts = 109.563255
current_seq = 355
count = 75
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 22us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.563858
last_received_ts = 109.563255
last_seq = 355
current_ts = 109.580536
current_received_ts = 109.579933
current_seq = 356
count = 76
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.580536
last_received_ts = 109.579933
last_seq = 356
current_ts = 109.597221
current_received_ts = 109.596619
current_seq = 357
count = 77
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.597221
last_received_ts = 109.596619
last_seq = 357
current_ts = 109.613899
current_received_ts = 109.613297
current_seq = 358
count = 78
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.613899
last_received_ts = 109.613297
last_seq = 358
current_ts = 109.630585
current_received_ts = 109.629974
current_seq = 359
count = 79
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 46us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.630585
last_received_ts = 109.629974
last_seq = 359
current_ts = 109.647263
current_received_ts = 109.646660
current_seq = 360
count = 80
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.647263
last_received_ts = 109.646660
last_seq = 360
current_ts = 109.663948
current_received_ts = 109.663338
current_seq = 361
count = 81
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.663948
last_received_ts = 109.663338
last_seq = 361
current_ts = 109.680626
current_received_ts = 109.680031
current_seq = 362
count = 82
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.680626
last_received_ts = 109.680031
last_seq = 362
current_ts = 109.697311
current_received_ts = 109.696701
current_seq = 363
count = 83
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.697311
last_received_ts = 109.696701
last_seq = 363
current_ts = 109.713989
current_received_ts = 109.713387
current_seq = 364
count = 84
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.713989
last_received_ts = 109.713387
last_seq = 364
current_ts = 109.730659
current_received_ts = 109.730080
current_seq = 365
count = 85
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.730659
last_received_ts = 109.730080
last_seq = 365
current_ts = 109.747345
current_received_ts = 109.746758
current_seq = 366
count = 86
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.747345
last_received_ts = 109.746758
last_seq = 366
current_ts = 109.764030
current_received_ts = 109.763428
current_seq = 367
count = 87
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.764030
last_received_ts = 109.763428
last_seq = 367
current_ts = 109.780701
current_received_ts = 109.780121
current_seq = 368
count = 88
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.780701
last_received_ts = 109.780121
last_seq = 368
current_ts = 109.797394
current_received_ts = 109.796791
current_seq = 369
count = 89
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.797394
last_received_ts = 109.796791
last_seq = 369
current_ts = 109.814079
current_received_ts = 109.813469
current_seq = 370
count = 90
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.814079
last_received_ts = 109.813469
last_seq = 370
current_ts = 109.830757
current_received_ts = 109.830154
current_seq = 371
count = 91
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.830757
last_received_ts = 109.830154
last_seq = 371
current_ts = 109.847443
current_received_ts = 109.846832
current_seq = 372
count = 92
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.847443
last_received_ts = 109.846832
last_seq = 372
current_ts = 109.864120
current_received_ts = 109.863518
current_seq = 373
count = 93
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.864120
last_received_ts = 109.863518
last_seq = 373
current_ts = 109.880798
current_received_ts = 109.880196
current_seq = 374
count = 94
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.880798
last_received_ts = 109.880196
last_seq = 374
current_ts = 109.897484
current_received_ts = 109.896873
current_seq = 375
count = 95
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.897484
last_received_ts = 109.896873
last_seq = 375
current_ts = 109.914154
current_received_ts = 109.913567
current_seq = 376
count = 96
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.914154
last_received_ts = 109.913567
last_seq = 376
current_ts = 109.930847
current_received_ts = 109.930244
current_seq = 377
count = 97
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.930847
last_received_ts = 109.930244
last_seq = 377
current_ts = 109.947517
current_received_ts = 109.946922
current_seq = 378
count = 98
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 20us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.947517
last_received_ts = 109.946922
last_seq = 378
current_ts = 109.964211
current_received_ts = 109.963661
current_seq = 379
count = 99
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 291us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.964211
last_received_ts = 109.963661
last_seq = 379
current_ts = 109.980888
current_received_ts = 109.980293
current_seq = 380
count = 100
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 27us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.980888
last_received_ts = 109.980293
last_seq = 380
current_ts = 109.997574
current_received_ts = 109.996971
current_seq = 381
count = 101
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 55us
(kms_flip:2961) DEBUG: name = flip
last_ts = 109.997574
last_received_ts = 109.996971
last_seq = 381
current_ts = 110.014252
current_received_ts = 110.013649
current_seq = 382
count = 102
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.014252
last_received_ts = 110.013649
last_seq = 382
current_ts = 110.030930
current_received_ts = 110.030334
current_seq = 383
count = 103
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.030930
last_received_ts = 110.030334
last_seq = 383
current_ts = 110.047615
current_received_ts = 110.047050
current_seq = 384
count = 104
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.047615
last_received_ts = 110.047050
last_seq = 384
current_ts = 110.064293
current_received_ts = 110.063690
current_seq = 385
count = 105
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.064293
last_received_ts = 110.063690
last_seq = 385
current_ts = 110.080978
current_received_ts = 110.080376
current_seq = 386
count = 106
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.080978
last_received_ts = 110.080376
last_seq = 386
current_ts = 110.097656
current_received_ts = 110.097061
current_seq = 387
count = 107
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.097656
last_received_ts = 110.097061
last_seq = 387
current_ts = 110.114326
current_received_ts = 110.113739
current_seq = 388
count = 108
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.114326
last_received_ts = 110.113739
last_seq = 388
current_ts = 110.131020
current_received_ts = 110.130478
current_seq = 389
count = 109
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 387us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.131020
last_received_ts = 110.130478
last_seq = 389
current_ts = 110.147705
current_received_ts = 110.147110
current_seq = 390
count = 110
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.147705
last_received_ts = 110.147110
last_seq = 390
current_ts = 110.164383
current_received_ts = 110.163780
current_seq = 391
count = 111
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.164383
last_received_ts = 110.163780
last_seq = 391
current_ts = 110.181068
current_received_ts = 110.180466
current_seq = 392
count = 112
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.181068
last_received_ts = 110.180466
last_seq = 392
current_ts = 110.197746
current_received_ts = 110.197144
current_seq = 393
count = 113
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.197746
last_received_ts = 110.197144
last_seq = 393
current_ts = 110.214432
current_received_ts = 110.213821
current_seq = 394
count = 114
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.214432
last_received_ts = 110.213821
last_seq = 394
current_ts = 110.231110
current_received_ts = 110.230507
current_seq = 395
count = 115
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.231110
last_received_ts = 110.230507
last_seq = 395
current_ts = 110.247787
current_received_ts = 110.247185
current_seq = 396
count = 116
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.247787
last_received_ts = 110.247185
last_seq = 396
current_ts = 110.264473
current_received_ts = 110.263870
current_seq = 397
count = 117
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.264473
last_received_ts = 110.263870
last_seq = 397
current_ts = 110.281151
current_received_ts = 110.280548
current_seq = 398
count = 118
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.281151
last_received_ts = 110.280548
last_seq = 398
current_ts = 110.297836
current_received_ts = 110.297234
current_seq = 399
count = 119
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.297836
last_received_ts = 110.297234
last_seq = 399
current_ts = 110.314514
current_received_ts = 110.313911
current_seq = 400
count = 120
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.314514
last_received_ts = 110.313911
last_seq = 400
current_ts = 110.331200
current_received_ts = 110.330589
current_seq = 401
count = 121
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.331200
last_received_ts = 110.330589
last_seq = 401
current_ts = 110.347878
current_received_ts = 110.347275
current_seq = 402
count = 122
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.347878
last_received_ts = 110.347275
last_seq = 402
current_ts = 110.364555
current_received_ts = 110.363953
current_seq = 403
count = 123
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.364555
last_received_ts = 110.363953
last_seq = 403
current_ts = 110.381241
current_received_ts = 110.380638
current_seq = 404
count = 124
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.381241
last_received_ts = 110.380638
last_seq = 404
current_ts = 110.397919
current_received_ts = 110.397316
current_seq = 405
count = 125
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.397919
last_received_ts = 110.397316
last_seq = 405
current_ts = 110.414597
current_received_ts = 110.413994
current_seq = 406
count = 126
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.414597
last_received_ts = 110.413994
last_seq = 406
current_ts = 110.431282
current_received_ts = 110.430679
current_seq = 407
count = 127
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.431282
last_received_ts = 110.430679
last_seq = 407
current_ts = 110.447960
current_received_ts = 110.447357
current_seq = 408
count = 128
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.447960
last_received_ts = 110.447357
last_seq = 408
current_ts = 110.464630
current_received_ts = 110.464043
current_seq = 409
count = 129
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.464630
last_received_ts = 110.464043
last_seq = 409
current_ts = 110.481323
current_received_ts = 110.480721
current_seq = 410
count = 130
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.481323
last_received_ts = 110.480721
last_seq = 410
current_ts = 110.498001
current_received_ts = 110.497398
current_seq = 411
count = 131
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.498001
last_received_ts = 110.497398
last_seq = 411
current_ts = 110.514671
current_received_ts = 110.514084
current_seq = 412
count = 132
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 25us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.514671
last_received_ts = 110.514084
last_seq = 412
current_ts = 110.531357
current_received_ts = 110.530769
current_seq = 413
count = 133
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.531357
last_received_ts = 110.530769
last_seq = 413
current_ts = 110.548042
current_received_ts = 110.547508
current_seq = 414
count = 134
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 34us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.548042
last_received_ts = 110.547508
last_seq = 414
current_ts = 110.564720
current_received_ts = 110.564095
current_seq = 415
count = 135
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 15us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.564720
last_received_ts = 110.564095
last_seq = 415
current_ts = 110.581406
current_received_ts = 110.580795
current_seq = 416
count = 136
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 278us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.581406
last_received_ts = 110.580795
last_seq = 416
current_ts = 110.598076
current_received_ts = 110.597572
current_seq = 417
count = 137
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 11us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.598076
last_received_ts = 110.597572
last_seq = 417
current_ts = 110.614761
current_received_ts = 110.614182
current_seq = 418
count = 138
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 16us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.614761
last_received_ts = 110.614182
last_seq = 418
current_ts = 110.631432
current_received_ts = 110.630867
current_seq = 419
count = 139
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 26us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.631432
last_received_ts = 110.630867
last_seq = 419
current_ts = 110.648132
current_received_ts = 110.647568
current_seq = 420
count = 140
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 309us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.648132
last_received_ts = 110.647568
last_seq = 420
current_ts = 110.664810
current_received_ts = 110.664238
current_seq = 421
count = 141
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 316us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.664810
last_received_ts = 110.664238
last_seq = 421
current_ts = 110.681480
current_received_ts = 110.680916
current_seq = 422
count = 142
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 31us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.681480
last_received_ts = 110.680916
last_seq = 422
current_ts = 110.698174
current_received_ts = 110.697578
current_seq = 423
count = 143
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 23us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.698174
last_received_ts = 110.697578
last_seq = 423
current_ts = 110.714844
current_received_ts = 110.714272
current_seq = 424
count = 144
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 24us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.714844
last_received_ts = 110.714272
last_seq = 424
current_ts = 110.731522
current_received_ts = 110.730972
current_seq = 425
count = 145
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 38us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.731522
last_received_ts = 110.730972
last_seq = 425
current_ts = 110.748215
current_received_ts = 110.747620
current_seq = 426
count = 146
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 21us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.748215
last_received_ts = 110.747620
last_seq = 426
current_ts = 110.764885
current_received_ts = 110.764267
current_seq = 427
count = 147
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 14us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.764885
last_received_ts = 110.764267
last_seq = 427
current_ts = 110.781570
current_received_ts = 110.780960
current_seq = 428
count = 148
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 298us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.781570
last_received_ts = 110.780960
last_seq = 428
current_ts = 110.798248
current_received_ts = 110.797607
current_seq = 429
count = 149
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 12us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.798248
last_received_ts = 110.797607
last_seq = 429
current_ts = 110.814926
current_received_ts = 110.814316
current_seq = 430
count = 150
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 285us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.814926
last_received_ts = 110.814316
last_seq = 430
current_ts = 110.831619
current_received_ts = 110.831024
current_seq = 431
count = 151
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 14us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.831619
last_received_ts = 110.831024
last_seq = 431
current_ts = 110.848297
current_received_ts = 110.847687
current_seq = 432
count = 152
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 22us
(kms_flip:2961) DEBUG: name = flip
last_ts = 110.848297
last_received_ts = 110.847687
last_seq = 432
current_ts = 110.864975
current_received_ts = 110.864510
current_seq = 433
count = 153
seq_step = 1
(kms_flip:2961) DEBUG: Vblank took 511us
(kms_flip:2961) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:910:
(kms_flip:2961) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2961) igt_core-INFO: Stack trace:
(kms_flip:2961) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2961) igt_core-INFO:   #1 ../tests/kms_flip.c:905 run_test_on_crtc_set.constprop.0()
(kms_flip:2961) igt_core-INFO:   #2 ../tests/kms_flip.c:1831 run_test()
(kms_flip:2961) igt_core-INFO:   #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
(kms_flip:2961) igt_core-INFO:   #4 ../tests/kms_flip.c:1987 main()
(kms_flip:2961) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2961) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2961) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-DP4: FAIL (3.136s)
Dmesg
<6> [121.842345] [IGT] kms_flip: starting dynamic subtest C-DP4
<7> [121.844104] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:293]
<7> [121.844941] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:296]
<7> [121.904978] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [121.905135] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [121.905229] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [121.905405] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [121.905617] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [121.905767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [121.905906] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [121.906044] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [121.906179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [121.906313] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [121.906441] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [121.906578] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [121.906704] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [121.906831] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [121.906954] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [121.907075] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [121.907195] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [121.907313] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [121.907428] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [121.907543] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [121.907673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [121.907790] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [121.907901] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [121.908013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [121.908124] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [121.908232] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [121.908340] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [121.908446] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [121.908549] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [121.908665] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [121.908769] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [121.908872] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [121.908975] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [121.909077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [121.909179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [121.909273] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [121.909366] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [121.909459] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [121.909550] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [121.909651] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [121.909749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [121.909843] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [121.909978] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [121.910102] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [121.910210] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:129:cursor B] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [121.910301] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [121.910397] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [121.910486] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [121.910587] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:134:pipe B] enable: no [modeset]
<7> [121.910679] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [121.910767] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [121.910853] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [121.910939] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [121.911026] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [121.911105] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [121.911218] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C] Disable audio codec on [CRTC:134:pipe B]
<7> [121.939742] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port C
<7> [121.940321] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [121.962076] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling DDI_IO_C
<7> [121.962559] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_C
<7> [121.963016] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DDI A/PHY A]
<7> [121.963361] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:242:DP-MST A]
<7> [121.963723] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:243:DP-MST B]
<7> [121.964037] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:244:DP-MST C]
<7> [121.964339] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:245:DP-MST D]
<7> [121.964670] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:259:DDI B/PHY B]
<7> [121.964986] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DDI C/PHY C]
<7> [121.965326] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST A]
<7> [121.965681] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST B]
<7> [121.966015] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:267:DP-MST C]
<7> [121.966344] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:268:DP-MST D]
<7> [121.966701] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:275:DDI D/PHY D]
<7> [121.967034] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:277:DP-MST A]
<7> [121.967366] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:278:DP-MST B]
<7> [121.967722] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:279:DP-MST C]
<7> [121.968049] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DP-MST D]
<7> [121.968375] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:264:DP-4]
<7> [121.968769] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [121.969316] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [121.969834] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [121.970323] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:134:pipe B]
<7> [121.970803] xe 0000:03:00.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 163200 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [121.971250] xe 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=408
<7> [121.972076] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [121.972560] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [121.972778] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [121.973491] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [121.973339] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [121.973584] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:264:DP-4]
<7> [121.973813] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:263:DDI C/PHY C][CRTC:186:pipe C] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 max link_bpp 36.0000
<7> [121.974168] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 link rate required 668250 available 1080000
<7> [121.974519] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:186:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [121.974862] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:263:DDI C/PHY C] [CRTC:186:pipe C]
<7> [121.975175] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [121.975483] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [121.975803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [121.976077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [121.976338] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [121.976608] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [121.976865] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [121.977096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [121.977321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [121.977551] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [121.977773] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [121.977980] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [121.978178] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [121.978368] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [121.978560] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [121.978740] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [121.978920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [121.979096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [121.979266] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [121.979427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [121.979600] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [121.979762] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [121.979920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [121.980073] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [121.980223] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [121.980368] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [121.980519] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [121.980664] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [121.980823] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [121.980975] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [121.981105] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [121.981233] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [121.981360] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [121.981483] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [121.981622] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [121.981746] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [121.981864] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [121.981977] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [121.982086] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:186:pipe C] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [121.982199] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:186:pipe C] fastset requirement not met, forcing full modeset
<7> [121.982332] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [121.982433] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:186:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [121.982574] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [121.982667] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:181:cursor C] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [121.982758] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:136: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> [121.982847] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [121.982937] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [121.983018] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:136:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [121.983098] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:186:pipe C] data rate 594000 num active planes 1
<7> [121.983205] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 50000 required 624
<7> [121.983304] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] No SAGV, using single QGV point mask 0x1
<7> [121.983409] xe 0000:03:00.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:136:plane 1C] min cdclk (74250 kHz) > [CRTC:186:pipe C] min cdclk (0 kHz)
<7> [121.983511] xe 0000:03:00.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (11602 kHz) > old min cdclk (0 kHz)
<7> [121.983607] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [121.983701] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 204000 kHz, actual 204000 kHz
<7> [121.983792] xe 0000:03:00.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [121.983877] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:186:pipe C] enable: yes [modeset]
<7> [121.983967] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [121.984055] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [121.984141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [121.984222] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [121.984301] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [121.984381] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [121.984459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [121.984542] 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> [121.984618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [121.984693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [121.984768] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [121.984843] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [121.984917] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [121.984991] 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> [121.985061] 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> [121.985132] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [121.985201] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [121.985270] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [121.985335] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [121.985400] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [121.985464] 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> [121.985536] 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> [121.985601] 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> [121.985666] 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> [121.985730] 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> [121.985795] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [121.985858] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [121.985922] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [121.985985] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [121.986050] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [121.986117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x41eb0, dpll_md: 0x18000000, fp0: 0x88228fe, fp1: 0x2b000020
<7> [121.986182] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [121.986246] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [121.986310] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [121.986374] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986437] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986504] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986529] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [121.986567] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [121.986630] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [121.986693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986755] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986818] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.986881] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [121.986945] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:136:plane 1C] fb: [FB:293] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [121.987010] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [121.987076] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [121.987144] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:plane 2C] fb: [NOFB], visible: no
<7> [121.987210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 3C] fb: [NOFB], visible: no
<7> [121.987275] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:163:plane 4C] fb: [NOFB], visible: no
<7> [121.987339] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:172:plane 5C] fb: [NOFB], visible: no
<7> [121.987403] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:181:cursor C] fb: [NOFB], visible: no
<7> [121.987772] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [121.987881] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [121.988018] xe 0000:03:00.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 204000 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [121.988097] xe 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=510
<7> [121.988167] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DDI A/PHY A]
<7> [121.988233] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:242:DP-MST A]
<7> [121.988296] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:243:DP-MST B]
<7> [121.988356] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:244:DP-MST C]
<7> [121.988416] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:245:DP-MST D]
<7> [121.988475] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:259:DDI B/PHY B]
<7> [121.988541] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DDI C/PHY C]
<7> [121.988599] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST A]
<7> [121.988657] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST B]
<7> [121.988714] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:267:DP-MST C]
<7> [121.988771] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:268:DP-MST D]
<7> [121.988827] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:275:DDI D/PHY D]
<7> [121.988884] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:277:DP-MST A]
<7> [121.988943] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:278:DP-MST B]
<7> [121.989001] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:279:DP-MST C]
<7> [121.989058] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DP-MST D]
<7> [121.989113] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [121.989243] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_C
<7> [121.990554] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling DDI_IO_C
<7> [121.992694] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [122.001491] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [122.002912] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [122.004142] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Using LINK_BW_SET value 0a
<7> [122.005489] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [122.005557] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Using DP training pattern TPS1
<7> [122.012717] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Clock recovery OK
<7> [122.012825] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Using DP training pattern TPS3
<7> [122.020711] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Channel EQ done. DP Training successful
<7> [122.020936] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [122.021650] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [122.038565] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:264:DP-4][ENCODER:263:DDI C/PHY C] Enable audio codec on [CRTC:186:pipe C], 40 bytes ELD
<7> [122.055336] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:264:DP-4]
<7> [122.055591] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:186:pipe C]
<7> [122.064790] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [124.513452] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:241:HDMI-A-1]
<7> [124.527054] xe 0000:00:02.0: [drm:xe_gt_resume [xe]] GT0: resuming
<7> [124.527822] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying GT save-restore MMIOs
<7> [124.527904] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x9424] = 0xfffffffc
<7> [124.527969] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x9550] = 0x000003ff
<7> [124.528035] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1c3f10] = 0x00400000
<7> [124.528094] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1d3f10] = 0x00400000
<7> [124.528151] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K
<7> [124.528218] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [592K, 1420K)
<7> [124.529552] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] GT0: ADS capture alloc size changed from 36864 to 32768
<7> [124.530140] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1300MHz (req 1500MHz), status = 0x00000072 [0x39/00]
<7> [124.530505] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1300MHz (req 1500MHz), status = 0x00000074 [0x3A/00]
<7> [124.530579] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1300MHz (req 1500MHz), status = 0x0000002E [0x17/00]
<7> [124.530627] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1300MHz (req 1500MHz), status = 0x800030EC [0x76/30]
<7> [124.530741] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: init took 0ms, freq = 1300MHz (req = 1500MHz), before = 1300MHz, status = 0x8002F0EC, timeouts = 0
<7> [124.530961] xe 0000:00:02.0: [drm:xe_guc_ct_enable [xe]] GT0: GuC CT communication channel enabled
<7> [124.533257] xe 0000:00:02.0: [drm:xe_huc_auth [xe]] GT0: HuC: authenticated via GuC
<7> [124.533391] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: flag:0x3
<7> [124.533450] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: mocs entries: 64
<7> [124.533506] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[0] 0x4000 0x37
<7> [124.533561] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[1] 0x4004 0x37
<7> [124.533615] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[2] 0x4008 0x37
<7> [124.533668] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[3] 0x400c 0x5
<7> [124.533721] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[4] 0x4010 0x5
<7> [124.533774] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[5] 0x4014 0x37
<7> [124.533826] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[6] 0x4018 0x17
<7> [124.533878] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[7] 0x401c 0x17
<7> [124.533930] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[8] 0x4020 0x27
<7> [124.533982] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[9] 0x4024 0x27
<7> [124.534033] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[10] 0x4028 0x77
<7> [124.534085] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[11] 0x402c 0x77
<7> [124.534133] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[12] 0x4030 0x57
<7> [124.534182] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[13] 0x4034 0x57
<7> [124.534231] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[14] 0x4038 0x67
<7> [124.534280] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[15] 0x403c 0x67
<7> [124.534328] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[16] 0x4040 0x37
<7> [124.534388] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[17] 0x4044 0x37
<7> [124.534437] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[18] 0x4048 0x60037
<7> [124.534485] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[19] 0x404c 0x737
<7> [124.534533] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[20] 0x4050 0x337
<7> [124.534580] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[21] 0x4054 0x137
<7> [124.534627] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[22] 0x4058 0x3b7
<7> [124.534675] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[23] 0x405c 0x7b7
<7> [124.534723] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[24] 0x4060 0x37
<7> [124.534770] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[25] 0x4064 0x37
<7> [124.534817] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[26] 0x4068 0x37
<7> [124.534865] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[27] 0x406c 0x37
<7> [124.534912] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[28] 0x4070 0x37
<7> [124.534959] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[29] 0x4074 0x37
<7> [124.535007] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[30] 0x4078 0x37
<7> [124.535054] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[31] 0x407c 0x37
<7> [124.535101] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[32] 0x4080 0x37
<7> [124.535149] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[33] 0x4084 0x37
<7> [124.535195] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[34] 0x4088 0x37
<7> [124.535244] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[35] 0x408c 0x37
<7> [124.535291] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[36] 0x4090 0x37
<7> [124.535339] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[37] 0x4094 0x37
<7> [124.535391] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[38] 0x4098 0x37
<7> [124.535439] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[39] 0x409c 0x37
<7> [124.535486] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[40] 0x40a0 0x37
<7> [124.535534] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[41] 0x40a4 0x37
<7> [124.535582] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[42] 0x40a8 0x37
<7> [124.535630] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[43] 0x40ac 0x37
<7> [124.535677] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[44] 0x40b0 0x37
<7> [124.535725] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[45] 0x40b4 0x37
<7> [124.535773] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[46] 0x40b8 0x37
<7> [124.535820] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[47] 0x40bc 0x37
<7> [124.535867] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[48] 0x40c0 0x37
<7> [124.535915] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[49] 0x40c4 0x5
<7> [124.535962] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[50] 0x40c8 0x37
<7> [124.536007] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[51] 0x40cc 0x5
<7> [124.536054] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[52] 0x40d0 0x37
<7> [124.536101] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[53] 0x40d4 0x37
<7> [124.536149] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[54] 0x40d8 0x37
<7> [124.536196] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[55] 0x40dc 0x37
<7> [124.536243] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[56] 0x40e0 0x37
<7> [124.536291] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[57] 0x40e4 0x37
<7> [124.536338] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[58] 0x40e8 0x37
<7> [124.536414] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[59] 0x40ec 0x37
<7> [124.536482] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[60] 0x40f0 0x37
<7> [124.536532] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[61] 0x40f4 0x5
<7> [124.536581] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[62] 0x40f8 0x37
<7> [124.536630] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: GLOB_MOCS[63] 0x40fc 0x37
<7> [124.536676] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: l3cc entries: 64
<7> [124.536724] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[0] 0xb020 0x300030
<7> [124.536772] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[1] 0xb024 0x100030
<7> [124.536821] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[2] 0xb028 0x100030
<7> [124.536867] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[3] 0xb02c 0x300010
<7> [124.536914] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[4] 0xb030 0x300010
<7> [124.536962] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[5] 0xb034 0x300010
<7> [124.537009] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[6] 0xb038 0x300010
<7> [124.537057] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[7] 0xb03c 0x300010
<7> [124.537105] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[8] 0xb040 0x300030
<7> [124.537153] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[9] 0xb044 0x300030
<7> [124.537200] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[10] 0xb048 0x300030
<7> [124.537248] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[11] 0xb04c 0x300030
<7> [124.537295] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[12] 0xb050 0x300030
<7> [124.537343] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[13] 0xb054 0x300030
<7> [124.537406] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[14] 0xb058 0x300030
<7> [124.537455] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[15] 0xb05c 0x300030
<7> [124.537503] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[16] 0xb060 0x300030
<7> [124.537549] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[17] 0xb064 0x300030
<7> [124.537597] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[18] 0xb068 0x300030
<7> [124.537645] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[19] 0xb06c 0x300030
<7> [124.537693] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[20] 0xb070 0x300030
<7> [124.537738] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[21] 0xb074 0x300030
<7> [124.537786] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[22] 0xb078 0x300030
<7> [124.537833] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[23] 0xb07c 0x300030
<7> [124.537880] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[24] 0xb080 0x300030
<7> [124.537926] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[25] 0xb084 0x100010
<7> [124.537973] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[26] 0xb088 0x300030
<7> [124.538019] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[27] 0xb08c 0x300030
<7> [124.538066] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[28] 0xb090 0x300030
<7> [124.538112] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[29] 0xb094 0x300030
<7> [124.538159] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[30] 0xb098 0x300010
<7> [124.538207] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] GT0: LNCFCMOCS[31] 0xb09c 0x100010
<7> [124.538261] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying rcs0 save-restore MMIOs
<7> [124.538320] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x20a0] = 0x24a80000
<7> [124.538376] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x20c4] = 0x3f7e0306
<7> [124.538443] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x20e0] = 0x40004000
<7> [124.538497] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x20ec] = 0x00020002
<7> [124.538551] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24d0] = 0x10002349
<7> [124.538630] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24d4] = 0x00007010
<7> [124.538681] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24d8] = 0x00007018
<7> [124.538732] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24dc] = 0x1000dafc
<7> [124.538853] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24e0] = 0x1000db01
<7> [124.538901] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x24e4] = 0x0000db1c
<7> [124.538953] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0xb004] = 0x2fc0100f
<7> [124.539007] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0xe18c] = 0x80018001
<7> [124.539060] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0xe48c] = 0x02000200
<7> [124.539113] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0xe4f4] = 0x41004100
<7> [124.539165] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying bcs0 save-restore MMIOs
<7> [124.539215] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x220c4] = 0x3f7e0306
<7> [124.539267] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x224d0] = 0x100223a8
<7> [124.539319] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying vcs0 save-restore MMIOs
<7> [124.539368] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1c00c4] = 0x3f7e0306
<7> [124.539432] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1c04d0] = 0x101c03a8
<7> [124.539490] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying vcs2 save-restore MMIOs
<7> [124.539541] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1d00c4] = 0x3f7e0306
<7> [124.539594] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1d04d0] = 0x101d03a8
<7> [124.539650] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying vecs0 save-restore MMIOs
<7> [124.539701] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1c80c4] = 0x3f7e0306
<7> [124.539753] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x1c84d0] = 0x101c83a8
<7> [124.539820] xe 0000:00:02.0: [drm:xe_gt_resume [xe]] GT0: resumed
<7> [124.540455] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [124.540558] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_config_restart [xe]] GT0: PF: pushed 0 skip 7 of 7 VFs configurations
<7> [124.540656] xe 0000:00:02.0: [drm:pf_worker_restart_func [xe]] GT0: PF: restart completed
<7> [124.545419] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:251:HDMI-A-2]
<7> [124.550466] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:255:DP-1]
<7> [124.555464] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:264:HDMI-A-3]
<7> [124.560388] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:268:DP-2]
<7> [124.565408] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:276:HDMI-A-4]
<7> [124.570411] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:241:HDMI-A-1]
<7> [124.575400] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:251:HDMI-A-2]
<7> [124.580394] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:255:DP-1]
<7> [124.585393] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:264:HDMI-A-3]
<7> [124.590364] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:268:DP-2]
<7> [124.595372] xe 0000:00:02.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:276:HDMI-A-4]
<7> [124.600381] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<6> [124.979009] [IGT] kms_flip: finished subtest C-DP4, FAIL
Created at 2025-03-16 02:22:54